builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0057 starttime: 1488466277.11 results: success (0) buildid: 20170302050023 builduid: 4d53ef886bd04211aa5e3df27ce830ab revision: 49c606518e60a1f51c3793d929b3c8670b9be63a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-02 06:51:17.109695) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-02 06:51:17.110104) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-02 06:51:17.110375) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-02 06:51:17.138443) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:51:17.138813) ========= 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.HUg90xWrAf/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.ULUt2vuXjL/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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-03-02 06:51:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2017-03-02 06:51:17 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143072 ========= master_lag: 0.51 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:51:17.791152) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:51:17.791456) ========= 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.HUg90xWrAf/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.ULUt2vuXjL/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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.026713 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:51:17.843585) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-02 06:51:17.843940) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 49c606518e60a1f51c3793d929b3c8670b9be63a --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-aurora --rev 49c606518e60a1f51c3793d929b3c8670b9be63a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HUg90xWrAf/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.ULUt2vuXjL/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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-03-02 06:51:17,954 truncating revision to first 12 chars 2017-03-02 06:51:17,954 Setting DEBUG logging. 2017-03-02 06:51:17,954 attempt 1/10 2017-03-02 06:51:17,954 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/49c606518e60?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-02 06:51:19,332 unpacking tar archive at: mozilla-aurora-49c606518e60/testing/mozharness/ program finished with exit code 0 elapsedTime=1.659902 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-02 06:51:19.527184) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-02 06:51:19.527497) ========= script_repo_revision: 49c606518e60a1f51c3793d929b3c8670b9be63a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-02 06:51:19.527920) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-02 06:51:19.528177) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-02 06:51:19.548194) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-03-02 06:51:19.548604) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HUg90xWrAf/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.ULUt2vuXjL/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 06:51:19 INFO - MultiFileLogger online at 20170302 06:51:19 in /builds/slave/test 06:51:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:51:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:51:19 INFO - {'append_to_log': False, 06:51:19 INFO - 'base_work_dir': '/builds/slave/test', 06:51:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:51:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:51:19 INFO - 'branch': 'Mozilla-Aurora', 06:51:19 INFO - 'buildbot_json_path': 'buildprops.json', 06:51:19 INFO - 'config_files': ('talos/mac_config.py',), 06:51:19 INFO - 'default_actions': ('clobber', 06:51:19 INFO - 'read-buildbot-config', 06:51:19 INFO - 'download-and-extract', 06:51:19 INFO - 'populate-webroot', 06:51:19 INFO - 'create-virtualenv', 06:51:19 INFO - 'install', 06:51:19 INFO - 'run-tests'), 06:51:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:51:19 INFO - 'download_minidump_stackwalk': True, 06:51:19 INFO - 'download_symbols': 'ondemand', 06:51:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:51:19 INFO - 'tooltool.py': '/tools/tooltool.py', 06:51:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:51:19 INFO - '/tools/misc-python/virtualenv.py')}, 06:51:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:51:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:51:19 INFO - 'installer_path': 'installer.exe', 06:51:19 INFO - 'log_level': 'info', 06:51:19 INFO - 'log_name': 'talos', 06:51:19 INFO - 'log_to_console': True, 06:51:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:51:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:51:19 INFO - 'opt_config_files': (), 06:51:19 INFO - 'pip_index': False, 06:51:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:51:19 INFO - 'cmd': ('bash', 06:51:19 INFO - '-c', 06:51:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:51:19 INFO - 'enabled': True, 06:51:19 INFO - 'halt_on_failure': False, 06:51:19 INFO - 'name': 'check_screen_resolution'},), 06:51:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:51:19 INFO - 'cmd': ('bash', 06:51:19 INFO - '-c', 06:51:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:51:19 INFO - 'enabled': True, 06:51:19 INFO - 'halt_on_failure': False, 06:51:19 INFO - 'name': 'check_screen_resolution'},), 06:51:19 INFO - 'run_cmd_checks_enabled': True, 06:51:19 INFO - 'sps_profile': False, 06:51:19 INFO - 'sps_profile_interval': 0, 06:51:19 INFO - 'suite': 'dromaeojs-e10s', 06:51:19 INFO - 'system_bits': '32', 06:51:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:51:19 INFO - 'title': 't-yosemite-r7-0057', 06:51:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:51:19 INFO - 'use_talos_json': True, 06:51:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:51:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:51:19 INFO - 'work_dir': 'build'} 06:51:19 INFO - [mozharness: 2017-03-02 14:51:19.762356Z] Running clobber step. 06:51:19 INFO - Running pre-action listener: _resource_record_pre_action 06:51:19 INFO - Running main action method: clobber 06:51:19 INFO - rmtree: /builds/slave/test/build 06:51:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:51:22 INFO - Running post-action listener: _resource_record_post_action 06:51:22 INFO - [mozharness: 2017-03-02 14:51:22.297447Z] Finished clobber step (success) 06:51:22 INFO - [mozharness: 2017-03-02 14:51:22.297618Z] Running read-buildbot-config step. 06:51:22 INFO - Running pre-action listener: _resource_record_pre_action 06:51:22 INFO - Running main action method: read_buildbot_config 06:51:22 INFO - Using buildbot properties: 06:51:22 INFO - { 06:51:22 INFO - "project": "", 06:51:22 INFO - "product": "firefox", 06:51:22 INFO - "script_repo_revision": "production", 06:51:22 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 06:51:22 INFO - "repository": "", 06:51:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 06:51:22 INFO - "buildid": "20170302050023", 06:51:22 INFO - "pgo_build": "False", 06:51:22 INFO - "basedir": "/builds/slave/test", 06:51:22 INFO - "buildnumber": 18, 06:51:22 INFO - "slavename": "t-yosemite-r7-0057", 06:51:22 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 06:51:22 INFO - "platform": "yosemite_r7", 06:51:22 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 06:51:22 INFO - "branch": "mozilla-aurora", 06:51:22 INFO - "repo_path": "releases/mozilla-aurora", 06:51:22 INFO - "revision": "49c606518e60a1f51c3793d929b3c8670b9be63a", 06:51:22 INFO - "stage_platform": "macosx64", 06:51:22 INFO - "builduid": "4d53ef886bd04211aa5e3df27ce830ab", 06:51:22 INFO - "slavebuilddir": "test" 06:51:22 INFO - } 06:51:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 06:51:22 INFO - Running post-action listener: _resource_record_post_action 06:51:22 INFO - [mozharness: 2017-03-02 14:51:22.300912Z] Finished read-buildbot-config step (success) 06:51:22 INFO - [mozharness: 2017-03-02 14:51:22.301084Z] Running download-and-extract step. 06:51:22 INFO - Running pre-action listener: _resource_record_pre_action 06:51:22 INFO - Running main action method: download_and_extract 06:51:22 INFO - mkdir: /builds/slave/test/build/tests 06:51:22 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')]} 06:51:22 INFO - trying https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 06:51:22 INFO - Downloading https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 06:51:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 06:51:23 INFO - Downloaded 1199 bytes. 06:51:23 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 06:51:23 INFO - Using the following test package requirements: 06:51:23 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 06:51:23 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:51:23 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 06:51:23 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:51:23 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 06:51:23 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:51:23 INFO - u'jsshell-mac.zip'], 06:51:23 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:51:23 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 06:51:23 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 06:51:23 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:51:23 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 06:51:23 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:51:23 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 06:51:23 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:51:23 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 06:51:23 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:51:23 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 06:51:23 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 06:51:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 06:51:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 06:51:23 INFO - Fetch https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 06:51:24 INFO - Expected file size: 16357801 06:51:24 INFO - Obtained file size: 16357801 06:51:31 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 06:51:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 06:51:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 06:51:31 INFO - Fetch https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 06:51:32 INFO - Expected file size: 16357801 06:51:32 INFO - Obtained file size: 16357801 06:51:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 06:51:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 06:51:38 INFO - Fetch https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 06:51:40 INFO - Expected file size: 14403472 06:51:40 INFO - Obtained file size: 14403472 06:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:40 INFO - trying https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 06:51:40 INFO - Downloading https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 06:51:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 06:51:42 INFO - Downloaded 73900078 bytes. 06:51:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 06:51:42 INFO - mkdir: /builds/slave/test/properties 06:51:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:51:42 INFO - Writing to file /builds/slave/test/properties/build_url 06:51:42 INFO - Contents: 06:51:42 INFO - build_url:https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 06:51:43 INFO - Running post-action listener: _resource_record_post_action 06:51:43 INFO - Running post-action listener: set_extra_try_arguments 06:51:43 INFO - [mozharness: 2017-03-02 14:51:43.188625Z] Finished download-and-extract step (success) 06:51:43 INFO - [mozharness: 2017-03-02 14:51:43.188748Z] Running populate-webroot step. 06:51:43 INFO - Running pre-action listener: _resource_record_pre_action 06:51:43 INFO - Running main action method: populate_webroot 06:51:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:51:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:51:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:51:43 INFO - '--tppagecycles', 06:51:43 INFO - '7'], 06:51:43 INFO - 'tests': ['tsvgm']}}, 06:51:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:51:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:51:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:51:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:51:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:51:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:51:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:51:43 INFO - 'talos_options': ['--disable-e10s'], 06:51:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:51:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:51:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:51:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:51:43 INFO - 'talos_options': ['--disable-e10s'], 06:51:43 INFO - 'tests': ['damp', 'tps']}, 06:51:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:51:43 INFO - 'tests': ['damp', 'tps']}, 06:51:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:51:43 INFO - 'tests': ['dromaeo_dom']}, 06:51:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:51:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:51:43 INFO - 'tests': ['basic_compositor_video']}, 06:51:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:51:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:51:43 INFO - 'tests': ['a11yr', 06:51:43 INFO - 'ts_paint', 06:51:43 INFO - 'tpaint', 06:51:43 INFO - 'sessionrestore', 06:51:43 INFO - 'sessionrestore_no_auto_restore', 06:51:43 INFO - 'tabpaint']}, 06:51:43 INFO - 'other-e10s': {'tests': ['a11yr', 06:51:43 INFO - 'ts_paint', 06:51:43 INFO - 'tpaint', 06:51:43 INFO - 'sessionrestore', 06:51:43 INFO - 'sessionrestore_no_auto_restore', 06:51:43 INFO - 'tabpaint']}, 06:51:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:51:43 INFO - 'ts_paint', 06:51:43 INFO - 'tpaint', 06:51:43 INFO - 'sessionrestore', 06:51:43 INFO - 'sessionrestore_no_auto_restore', 06:51:43 INFO - 'tabpaint']}, 06:51:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:51:43 INFO - 'ts_paint', 06:51:43 INFO - 'tpaint', 06:51:43 INFO - 'sessionrestore', 06:51:43 INFO - 'sessionrestore_no_auto_restore', 06:51:43 INFO - 'tabpaint']}, 06:51:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:51:43 INFO - 'tests': ['a11yr', 06:51:43 INFO - 'ts_paint', 06:51:43 INFO - 'tpaint', 06:51:43 INFO - 'sessionrestore', 06:51:43 INFO - 'sessionrestore_no_auto_restore', 06:51:43 INFO - 'tabpaint']}, 06:51:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:51:43 INFO - 'tests': ['a11yr', 06:51:43 INFO - 'ts_paint', 06:51:43 INFO - 'tpaint', 06:51:43 INFO - 'sessionrestore', 06:51:43 INFO - 'sessionrestore_no_auto_restore', 06:51:43 INFO - 'tabpaint']}, 06:51:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:51:43 INFO - 'tests': ['tsvgx', 06:51:43 INFO - 'tsvgr_opacity', 06:51:43 INFO - 'tart', 06:51:43 INFO - 'tscrollx', 06:51:43 INFO - 'cart', 06:51:43 INFO - 'tsvg_static']}, 06:51:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:51:43 INFO - 'tsvgr_opacity', 06:51:43 INFO - 'tart', 06:51:43 INFO - 'tscrollx', 06:51:43 INFO - 'cart', 06:51:43 INFO - 'tsvg_static']}, 06:51:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:51:43 INFO - 'talos_options': ['--disable-e10s'], 06:51:43 INFO - 'tests': ['tp5o']}, 06:51:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:51:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:51:43 INFO - 'talos_options': ['--disable-e10s', 06:51:43 INFO - '--xperf_path', 06:51:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:51:43 INFO - 'tests': ['tp5n']}, 06:51:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:51:43 INFO - 'talos_options': ['--xperf_path', 06:51:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:51:43 INFO - 'tests': ['tp5n']}}, 06:51:43 INFO - 'talos.zip': {'path': '', 06:51:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:51:43 INFO - Running post-action listener: _resource_record_post_action 06:51:43 INFO - [mozharness: 2017-03-02 14:51:43.207793Z] Finished populate-webroot step (success) 06:51:43 INFO - [mozharness: 2017-03-02 14:51:43.207877Z] Running create-virtualenv step. 06:51:43 INFO - Running pre-action listener: _resource_record_pre_action 06:51:43 INFO - Running main action method: create_virtualenv 06:51:43 INFO - Creating virtualenv /builds/slave/test/build/venv 06:51:43 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 06:51:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:51:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:51:43 INFO - Using real prefix '/tools/python27' 06:51:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:51:44 INFO - Installing distribute.............................................................................................................................................................................................done. 06:51:46 INFO - Installing pip.................done. 06:51:46 INFO - Return code: 0 06:51:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:51:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:51:47 INFO - Reading from file tmpfile_stdout 06:51:47 INFO - Output received: 06:51:47 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) 06:51:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:51:47 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')]} 06:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:51:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:47 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')]} 06:51:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:51:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:47 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 0x10a050b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a053768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7834a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e2ed30>, '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 0x109d55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e23be8>, '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.HUg90xWrAf/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.ULUt2vuXjL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 06:51:47 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 06:51:47 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 06:51:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUg90xWrAf/Render', 06:51:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:51:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:51:47 INFO - 'HOME': '/Users/cltbld', 06:51:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:51:47 INFO - 'LOGNAME': 'cltbld', 06:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:47 INFO - 'MOZ_NO_REMOTE': '1', 06:51:47 INFO - 'NO_EM_RESTART': '1', 06:51:47 INFO - 'PAGER': '/bin/cat', 06:51:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:47 INFO - 'PWD': '/builds/slave/test', 06:51:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:51:47 INFO - 'SHELL': '/bin/bash', 06:51:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ULUt2vuXjL/Listeners', 06:51:47 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 06:51:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:51:47 INFO - 'USER': 'cltbld', 06:51:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:51:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:47 INFO - 'XPC_FLAGS': '0x0', 06:51:47 INFO - 'XPC_SERVICE_NAME': '0', 06:51:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:51:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:51:48 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 06:51:48 INFO - Cleaning up... 06:51:48 INFO - Return code: 0 06:51:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:51:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:51:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a050b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a053768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7834a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e2ed30>, '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 0x109d55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e23be8>, '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.HUg90xWrAf/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.ULUt2vuXjL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 06:51:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:51:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:51:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUg90xWrAf/Render', 06:51:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:51:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:51:48 INFO - 'HOME': '/Users/cltbld', 06:51:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:51:48 INFO - 'LOGNAME': 'cltbld', 06:51:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:48 INFO - 'MOZ_NO_REMOTE': '1', 06:51:48 INFO - 'NO_EM_RESTART': '1', 06:51:48 INFO - 'PAGER': '/bin/cat', 06:51:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:51:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:48 INFO - 'PWD': '/builds/slave/test', 06:51:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:51:48 INFO - 'SHELL': '/bin/bash', 06:51:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ULUt2vuXjL/Listeners', 06:51:48 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 06:51:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:51:48 INFO - 'USER': 'cltbld', 06:51:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:51:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:51:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:48 INFO - 'XPC_FLAGS': '0x0', 06:51:48 INFO - 'XPC_SERVICE_NAME': '0', 06:51:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:51:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:51:48 INFO - Downloading/unpacking psutil>=3.1.1 06:51: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 06:51: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 06:51: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 06:51: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 06:51: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 06:51: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 06:51:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:51:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:51:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:51:52 INFO - Installing collected packages: psutil 06:51:52 INFO - Running setup.py install for psutil 06:51:52 INFO - building 'psutil._psutil_osx' extension 06:51:52 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 06:51:53 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 06:51:53 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 06:51:53 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 06:51:53 INFO - building 'psutil._psutil_posix' extension 06:51:53 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 06:51:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:51:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:51:53 INFO - ^ 06:51:53 INFO - 1 warning generated. 06:51:53 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 06:51:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:51:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:51:53 INFO - Successfully installed psutil 06:51:53 INFO - Cleaning up... 06:51:53 INFO - Return code: 0 06:51:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:51:53 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')]} 06:51:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:51:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:53 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')]} 06:51:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:51:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:53 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 0x10a050b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a053768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7834a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e2ed30>, '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 0x109d55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e23be8>, '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.HUg90xWrAf/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.ULUt2vuXjL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 06:51:53 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 06:51:53 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 06:51:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUg90xWrAf/Render', 06:51:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:51:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:51:53 INFO - 'HOME': '/Users/cltbld', 06:51:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:51:53 INFO - 'LOGNAME': 'cltbld', 06:51:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:53 INFO - 'MOZ_NO_REMOTE': '1', 06:51:53 INFO - 'NO_EM_RESTART': '1', 06:51:53 INFO - 'PAGER': '/bin/cat', 06:51:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:51:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:53 INFO - 'PWD': '/builds/slave/test', 06:51:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:51:53 INFO - 'SHELL': '/bin/bash', 06:51:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ULUt2vuXjL/Listeners', 06:51:53 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 06:51:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:51:53 INFO - 'USER': 'cltbld', 06:51:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:51:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:51:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:53 INFO - 'XPC_FLAGS': '0x0', 06:51:53 INFO - 'XPC_SERVICE_NAME': '0', 06:51:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:51:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:51:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:51:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:51:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:51:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:51:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:51:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:51:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:51:56 INFO - Installing collected packages: mozsystemmonitor 06:51:56 INFO - Running setup.py install for mozsystemmonitor 06:51:56 INFO - Successfully installed mozsystemmonitor 06:51:56 INFO - Cleaning up... 06:51:57 INFO - Return code: 0 06:51:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:51:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:51:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:51:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a050b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a053768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7834a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e2ed30>, '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 0x109d55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e23be8>, '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.HUg90xWrAf/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.ULUt2vuXjL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 06:51:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:51:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:51:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUg90xWrAf/Render', 06:51:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:51:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:51:57 INFO - 'HOME': '/Users/cltbld', 06:51:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:51:57 INFO - 'LOGNAME': 'cltbld', 06:51:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:57 INFO - 'MOZ_NO_REMOTE': '1', 06:51:57 INFO - 'NO_EM_RESTART': '1', 06:51:57 INFO - 'PAGER': '/bin/cat', 06:51:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:51:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:57 INFO - 'PWD': '/builds/slave/test', 06:51:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:51:57 INFO - 'SHELL': '/bin/bash', 06:51:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ULUt2vuXjL/Listeners', 06:51:57 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 06:51:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:51:57 INFO - 'USER': 'cltbld', 06:51:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:51:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:51:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:57 INFO - 'XPC_FLAGS': '0x0', 06:51:57 INFO - 'XPC_SERVICE_NAME': '0', 06:51:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:51:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:51:57 INFO - Downloading/unpacking jsonschema==2.5.1 06:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:51:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:51:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:51:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:51:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:51:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:51:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:51:59 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 06:51:59 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 06:51:59 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 06:51:59 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 06:51:59 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 06:51:59 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 06:52:00 INFO - Downloading functools32-3.2.3-2.tar.gz 06:52:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:52:00 INFO - warning: no files found matching '*.txt' 06:52:00 INFO - no previously-included directories found matching 'build' 06:52:00 INFO - no previously-included directories found matching 'dist' 06:52:00 INFO - no previously-included directories found matching '.git*' 06:52:00 INFO - Installing collected packages: jsonschema, functools32 06:52:00 INFO - Running setup.py install for functools32 06:52:00 INFO - warning: no files found matching '*.txt' 06:52:00 INFO - no previously-included directories found matching 'build' 06:52:00 INFO - no previously-included directories found matching 'dist' 06:52:00 INFO - no previously-included directories found matching '.git*' 06:52:00 INFO - Successfully installed jsonschema functools32 06:52:00 INFO - Cleaning up... 06:52:00 INFO - Return code: 0 06:52:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:52:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:52:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a050b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a053768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7834a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e2ed30>, '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 0x109d55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e23be8>, '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.HUg90xWrAf/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.ULUt2vuXjL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 06:52:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:52:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:52:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUg90xWrAf/Render', 06:52:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:00 INFO - 'HOME': '/Users/cltbld', 06:52:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:00 INFO - 'LOGNAME': 'cltbld', 06:52:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:00 INFO - 'MOZ_NO_REMOTE': '1', 06:52:00 INFO - 'NO_EM_RESTART': '1', 06:52:00 INFO - 'PAGER': '/bin/cat', 06:52:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:00 INFO - 'PWD': '/builds/slave/test', 06:52:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:00 INFO - 'SHELL': '/bin/bash', 06:52:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ULUt2vuXjL/Listeners', 06:52:00 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 06:52:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:00 INFO - 'USER': 'cltbld', 06:52:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:00 INFO - 'XPC_FLAGS': '0x0', 06:52:00 INFO - 'XPC_SERVICE_NAME': '0', 06:52:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:52:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:52:01 INFO - Cleaning up... 06:52:01 INFO - Return code: 0 06:52:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:52:01 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')]} 06:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:52:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:01 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')]} 06:52:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:52:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:01 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 0x10a050b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a053768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7834a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e2ed30>, '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 0x109d55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e23be8>, '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.HUg90xWrAf/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.ULUt2vuXjL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 06:52:01 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 06:52:01 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 06:52:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUg90xWrAf/Render', 06:52:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:01 INFO - 'HOME': '/Users/cltbld', 06:52:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:01 INFO - 'LOGNAME': 'cltbld', 06:52:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:01 INFO - 'MOZ_NO_REMOTE': '1', 06:52:01 INFO - 'NO_EM_RESTART': '1', 06:52:01 INFO - 'PAGER': '/bin/cat', 06:52:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:01 INFO - 'PWD': '/builds/slave/test', 06:52:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:01 INFO - 'SHELL': '/bin/bash', 06:52:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ULUt2vuXjL/Listeners', 06:52:01 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 06:52:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:01 INFO - 'USER': 'cltbld', 06:52:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:01 INFO - 'XPC_FLAGS': '0x0', 06:52:01 INFO - 'XPC_SERVICE_NAME': '0', 06:52:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:52:01 INFO - Downloading/unpacking blobuploader==1.2.4 06:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:52:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:52:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:52:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:52:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:52:03 INFO - Downloading blobuploader-1.2.4.tar.gz 06:52:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:52:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:52:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:52:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:52:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:52:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:52:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:52:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:52:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:52:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:52:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:52:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:52:05 INFO - Downloading docopt-0.6.1.tar.gz 06:52:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:52:05 INFO - Installing collected packages: blobuploader, requests, docopt 06:52:05 INFO - Running setup.py install for blobuploader 06:52:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:52:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:52:05 INFO - Running setup.py install for requests 06:52:05 INFO - Running setup.py install for docopt 06:52:05 INFO - Successfully installed blobuploader requests docopt 06:52:05 INFO - Cleaning up... 06:52:06 INFO - Return code: 0 06:52:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:52:06 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')]} 06:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:52:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:06 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')]} 06:52:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:52:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:06 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 0x10a050b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a053768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7834a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e2ed30>, '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 0x109d55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e23be8>, '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.HUg90xWrAf/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.ULUt2vuXjL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 06:52:06 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 06:52:06 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 06:52:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUg90xWrAf/Render', 06:52:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:06 INFO - 'HOME': '/Users/cltbld', 06:52:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:06 INFO - 'LOGNAME': 'cltbld', 06:52:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:06 INFO - 'MOZ_NO_REMOTE': '1', 06:52:06 INFO - 'NO_EM_RESTART': '1', 06:52:06 INFO - 'PAGER': '/bin/cat', 06:52:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:06 INFO - 'PWD': '/builds/slave/test', 06:52:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:06 INFO - 'SHELL': '/bin/bash', 06:52:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ULUt2vuXjL/Listeners', 06:52:06 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 06:52:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:06 INFO - 'USER': 'cltbld', 06:52:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:06 INFO - 'XPC_FLAGS': '0x0', 06:52:06 INFO - 'XPC_SERVICE_NAME': '0', 06:52:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:52:06 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-fVYXeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:52:06 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-vHzpFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:52:06 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-CI_JSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:52:06 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-PV2Ft8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:52:06 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-fkGruF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:52:06 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-vbrhBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:52:06 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-PsaLhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:52:07 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-8fwOiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:52:07 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-7Y0p9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:52:07 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-wb9rii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:52:07 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-Eqn6i1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:52:07 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-qQKjYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:52:07 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-3WgbYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:52:07 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-09G6lv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:52:07 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-fyVJTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:52:07 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-y9ZvAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:52:08 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-VUO4EI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:52:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:52:08 INFO - Running setup.py install for manifestparser 06:52:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:52:08 INFO - Running setup.py install for mozcrash 06:52:08 INFO - Running setup.py install for mozdebug 06:52:08 INFO - Running setup.py install for mozdevice 06:52:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:52:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:52:08 INFO - Running setup.py install for mozfile 06:52:08 INFO - Running setup.py install for mozhttpd 06:52:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:52:08 INFO - Running setup.py install for mozinfo 06:52:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:52:09 INFO - Running setup.py install for mozInstall 06:52:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:52:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:52:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:52:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:52:09 INFO - Running setup.py install for mozleak 06:52:09 INFO - Running setup.py install for mozlog 06:52:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:52:09 INFO - Running setup.py install for moznetwork 06:52:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:52:09 INFO - Running setup.py install for mozprocess 06:52:09 INFO - Running setup.py install for mozprofile 06:52:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:52:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:52:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:52:10 INFO - Running setup.py install for mozrunner 06:52:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:52:10 INFO - Running setup.py install for mozscreenshot 06:52:10 INFO - Running setup.py install for moztest 06:52:10 INFO - Running setup.py install for mozversion 06:52:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:52:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:52:10 INFO - Cleaning up... 06:52:10 INFO - Return code: 0 06:52:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:52:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:52:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:10 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 0x10a050b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a053768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7834a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e2ed30>, '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 0x109d55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e23be8>, '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.HUg90xWrAf/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.ULUt2vuXjL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 06:52:10 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 06:52:10 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 06:52:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUg90xWrAf/Render', 06:52:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:10 INFO - 'HOME': '/Users/cltbld', 06:52:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:10 INFO - 'LOGNAME': 'cltbld', 06:52:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:10 INFO - 'MOZ_NO_REMOTE': '1', 06:52:10 INFO - 'NO_EM_RESTART': '1', 06:52:10 INFO - 'PAGER': '/bin/cat', 06:52:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:10 INFO - 'PWD': '/builds/slave/test', 06:52:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:10 INFO - 'SHELL': '/bin/bash', 06:52:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ULUt2vuXjL/Listeners', 06:52:10 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 06:52:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:10 INFO - 'USER': 'cltbld', 06:52:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:10 INFO - 'XPC_FLAGS': '0x0', 06:52:10 INFO - 'XPC_SERVICE_NAME': '0', 06:52:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:52:10 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-56NAEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:52:11 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)) 06:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:52:11 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-9EwdDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:52:11 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)) 06:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:52:11 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-tYfJuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:52:11 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)) 06:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:52:11 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-wh9lUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:52:11 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)) 06:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:52:11 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-3YXdFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:52:11 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)) 06:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:52:11 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-rPWej9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:52:11 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)) 06:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:52:11 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-7AQa_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:52:11 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)) 06:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:52:11 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-PcStbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:52:11 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)) 06:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:52:11 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-DtdczS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:52:11 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)) 06:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:52:11 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-vngOJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:52:12 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)) 06:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:52:12 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-WQA8hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:52:12 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)) 06:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:52:12 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-2zopJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:52:12 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)) 06:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:52:12 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-QtahNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:52:12 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)) 06:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:52:12 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-wQWaMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:52:12 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)) 06:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:52:12 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-E4spdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:52:12 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)) 06:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:52:12 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-NFljFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:52:12 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)) 06:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:52:12 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-ZtagJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:52:12 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)) 06:52:12 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)) 06:52:12 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)) 06:52:12 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)) 06:52:12 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)) 06:52:12 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)) 06:52:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:52:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:52:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:52:15 INFO - Downloading blessings-1.6.tar.gz 06:52:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:52:15 INFO - Installing collected packages: blessings 06:52:15 INFO - Running setup.py install for blessings 06:52:15 INFO - Successfully installed blessings 06:52:15 INFO - Cleaning up... 06:52:15 INFO - Return code: 0 06:52:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:52:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:52:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:52:15 INFO - Reading from file tmpfile_stdout 06:52:16 INFO - Current package versions: 06:52:16 INFO - blessings == 1.6 06:52:16 INFO - blobuploader == 1.2.4 06:52:16 INFO - docopt == 0.6.1 06:52:16 INFO - functools32 == 3.2.3-2 06:52:16 INFO - jsonschema == 2.5.1 06:52:16 INFO - manifestparser == 1.1 06:52:16 INFO - mozInstall == 1.12 06:52:16 INFO - mozcrash == 1.0 06:52:16 INFO - mozdebug == 0.1 06:52:16 INFO - mozdevice == 0.49 06:52:16 INFO - mozfile == 1.2 06:52:16 INFO - mozhttpd == 0.7 06:52:16 INFO - mozinfo == 0.9 06:52:16 INFO - mozleak == 0.1 06:52:16 INFO - mozlog == 3.4 06:52:16 INFO - moznetwork == 0.27 06:52:16 INFO - mozprocess == 0.24 06:52:16 INFO - mozprofile == 0.28 06:52:16 INFO - mozrunner == 6.13 06:52:16 INFO - mozscreenshot == 0.1 06:52:16 INFO - mozsystemmonitor == 0.3 06:52:16 INFO - moztest == 0.8 06:52:16 INFO - mozversion == 1.4 06:52:16 INFO - psutil == 3.1.1 06:52:16 INFO - requests == 1.2.3 06:52:16 INFO - wsgiref == 0.1.2 06:52:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:52:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:52:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:52:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:16 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 0x10a050b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a053768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7834a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e2ed30>, '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 0x109d55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e23be8>, '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.HUg90xWrAf/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.ULUt2vuXjL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 06:52:16 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 06:52:16 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 06:52:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUg90xWrAf/Render', 06:52:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:16 INFO - 'HOME': '/Users/cltbld', 06:52:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:16 INFO - 'LOGNAME': 'cltbld', 06:52:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:16 INFO - 'MOZ_NO_REMOTE': '1', 06:52:16 INFO - 'NO_EM_RESTART': '1', 06:52:16 INFO - 'PAGER': '/bin/cat', 06:52:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:16 INFO - 'PWD': '/builds/slave/test', 06:52:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:16 INFO - 'SHELL': '/bin/bash', 06:52:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ULUt2vuXjL/Listeners', 06:52:16 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 06:52:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:16 INFO - 'USER': 'cltbld', 06:52:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:16 INFO - 'XPC_FLAGS': '0x0', 06:52:16 INFO - 'XPC_SERVICE_NAME': '0', 06:52:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:52:16 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)) 06:52:16 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)) 06:52:16 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)) 06:52:16 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)) 06:52:16 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)) 06:52:16 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)) 06:52:16 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)) 06:52:16 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)) 06:52:16 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)) 06:52:16 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)) 06:52:16 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)) 06:52:16 INFO - Cleaning up... 06:52:16 INFO - Return code: 0 06:52:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:52:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:52:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:52:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a050b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a053768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7834a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e2ed30>, '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 0x109d55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e23be8>, '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.HUg90xWrAf/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.ULUt2vuXjL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 06:52:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:52:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:52:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUg90xWrAf/Render', 06:52:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:16 INFO - 'HOME': '/Users/cltbld', 06:52:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:16 INFO - 'LOGNAME': 'cltbld', 06:52:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:16 INFO - 'MOZ_NO_REMOTE': '1', 06:52:16 INFO - 'NO_EM_RESTART': '1', 06:52:16 INFO - 'PAGER': '/bin/cat', 06:52:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:16 INFO - 'PWD': '/builds/slave/test', 06:52:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:16 INFO - 'SHELL': '/bin/bash', 06:52:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ULUt2vuXjL/Listeners', 06:52:16 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 06:52:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:16 INFO - 'USER': 'cltbld', 06:52:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:16 INFO - 'XPC_FLAGS': '0x0', 06:52:16 INFO - 'XPC_SERVICE_NAME': '0', 06:52:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:52:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:52:16 INFO - Cleaning up... 06:52:16 INFO - Return code: 0 06:52:16 INFO - Running post-action listener: _resource_record_post_action 06:52:16 INFO - Running post-action listener: _start_resource_monitoring 06:52:16 INFO - Starting resource monitoring. 06:52:16 INFO - [mozharness: 2017-03-02 14:52:16.601529Z] Finished create-virtualenv step (success) 06:52:16 INFO - [mozharness: 2017-03-02 14:52:16.602477Z] Running install step. 06:52:16 INFO - Running pre-action listener: _resource_record_pre_action 06:52:16 INFO - Running main action method: install 06:52:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:52:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:52:16 INFO - Reading from file tmpfile_stdout 06:52:16 INFO - Detecting whether we're running mozinstall >=1.0... 06:52:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:52:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:52:16 INFO - Reading from file tmpfile_stdout 06:52:16 INFO - Output received: 06:52:16 INFO - Usage: mozinstall [options] installer 06:52:16 INFO - Options: 06:52:16 INFO - -h, --help show this help message and exit 06:52:16 INFO - -d DEST, --destination=DEST 06:52:16 INFO - Directory to install application into. [default: 06:52:16 INFO - "/builds/slave/test"] 06:52:16 INFO - --app=APP Application being installed. [default: firefox] 06:52:16 INFO - mkdir: /builds/slave/test/build/application 06:52:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:52:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 06:52:36 INFO - Reading from file tmpfile_stdout 06:52:36 INFO - Output received: 06:52:36 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 06:52:36 INFO - Running post-action listener: _resource_record_post_action 06:52:36 INFO - [mozharness: 2017-03-02 14:52:36.833011Z] Finished install step (success) 06:52:36 INFO - [mozharness: 2017-03-02 14:52:36.833185Z] Running run-tests step. 06:52:36 INFO - Running pre-action listener: _resource_record_pre_action 06:52:36 INFO - Running main action method: run_tests 06:52:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:52:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:52:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:52:36 INFO - 2017-03-02 06:52:36.847 screenresolution[1627:7396] starting screenresolution argv=screenresolution get 06:52:36 INFO - 2017-03-02 06:52:36.850 screenresolution[1627:7396] Display 0: 1600x1200x32@60 06:52:36 INFO - 2017-03-02 06:52:36.856 screenresolution[1628:7399] starting screenresolution argv=screenresolution list 06:52:36 INFO - Available Modes on Display 0 06:52:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:52:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:52:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:52:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:52:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:52:37 INFO - Intel Iris: 06:52:37 INFO - Chipset Model: Intel Iris 06:52:37 INFO - Type: GPU 06:52:37 INFO - Bus: Built-In 06:52:37 INFO - VRAM (Dynamic, Max): 1536 MB 06:52:37 INFO - Vendor: Intel (0x8086) 06:52:37 INFO - Device ID: 0x0a2e 06:52:37 INFO - Revision ID: 0x0009 06:52:37 INFO - Displays: 06:52:37 INFO - Display: 06:52:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:52:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:52:37 INFO - Display Serial Number: 1600x1200 60 06:52:37 INFO - Main Display: Yes 06:52:37 INFO - Mirror: Off 06:52:37 INFO - Online: Yes 06:52:37 INFO - Rotation: Supported 06:52:37 INFO - Return code: 0 06:52:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:52:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:52:37 INFO - Python 2.7.3 06:52:37 INFO - Return code: 0 06:52:37 INFO - grabbing minidump binary from tooltool 06:52:37 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')]} 06:52:37 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 0x109e2ed30>, '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 0x109d55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e23be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:52:37 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 06:52:37 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 06:52:37 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 06:52:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:52:37 INFO - Return code: 0 06:52:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:52:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:52:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:52:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:52:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:52:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0057', '--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 06:52:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0057 --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 06:52:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUg90xWrAf/Render', 06:52:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:37 INFO - 'HOME': '/Users/cltbld', 06:52:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:37 INFO - 'LOGNAME': 'cltbld', 06:52:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:52:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:37 INFO - 'MOZ_NO_REMOTE': '1', 06:52:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:52:37 INFO - 'NO_EM_RESTART': '1', 06:52:37 INFO - 'PAGER': '/bin/cat', 06:52:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:37 INFO - 'PWD': '/builds/slave/test', 06:52:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:52:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:37 INFO - 'SHELL': '/bin/bash', 06:52:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ULUt2vuXjL/Listeners', 06:52:37 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 06:52:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:37 INFO - 'USER': 'cltbld', 06:52:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:37 INFO - 'XPC_FLAGS': '0x0', 06:52:37 INFO - 'XPC_SERVICE_NAME': '0', 06:52:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0057', '--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 06:52:37 INFO - mozversion application_buildid: 20170302050023 06:52:37 INFO - mozversion application_changeset: 49c606518e60a1f51c3793d929b3c8670b9be63a 06:52:37 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:52:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:52:37 INFO - mozversion application_name: Firefox 06:52:37 INFO - mozversion application_remotingname: firefox-dev 06:52:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:52:37 INFO - mozversion application_vendor: Mozilla 06:52:37 INFO - mozversion application_version: 53.0a2 06:52:37 INFO - mozversion platform_buildid: 20170302050023 06:52:37 INFO - mozversion platform_changeset: 49c606518e60a1f51c3793d929b3c8670b9be63a 06:52:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:52:37 INFO - mozversion platform_version: 53.0a2 06:52:37 INFO - using testdate: 1488466357 06:52:37 INFO - actual date: 1488466357 06:52:37 INFO - starting webserver on 'localhost:49237' 06:52:37 INFO - SUITE-START | Running 2 tests 06:52:37 INFO - TEST-START | dromaeo_css 06:52:37 INFO - operating with platform_type : mac_ 06:52:37 INFO - Initialising browser for dromaeo_css test... 06:52:37 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/tmpOprmN2/profile http://localhost:49237/getInfo.html) 06:52:40 INFO - PROCESS | 1638 | 2017-03-02 06:52:40.223 plugin-container[1640:7578] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 06:52:40 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:52:40 INFO - PROCESS | 1638 | 2017-03-02 06:52:40.226 plugin-container[1640:7578] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950f, name = 'com.apple.CFPasteboardClient' 06:52:40 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:52:40 INFO - PROCESS | 1638 | 2017-03-02 06:52:40.226 plugin-container[1640:7578] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:52:40 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 06:52:40 INFO - PROCESS | 1638 | colorDepth:24 06:52:40 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 06:52:40 INFO - PROCESS | 1638 | __metrics 06:52:40 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 06:52:41 INFO - TEST-INFO | 1638: exit 0 06:52:41 INFO - Browser initialized. 06:52:41 INFO - Running cycle 1/1 for dromaeo_css test... 06:52:41 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/tmpOprmN2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:52:43 INFO - PROCESS | 1644 | 2017-03-02 06:52:43.771 plugin-container[1645:7879] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 06:52:43 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:52:43 INFO - PROCESS | 1644 | 2017-03-02 06:52:43.771 plugin-container[1645:7879] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 06:52:43 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:52:43 INFO - PROCESS | 1644 | 2017-03-02 06:52:43.771 plugin-container[1645:7879] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:52:53 INFO - PROCESS | 1644 | RSS: Main: 218734592 06:52:53 INFO - PROCESS | 1644 | 06:55:55 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 06:55:57 INFO - PROCESS | 1644 | RSS: Main: 235737088 06:55:57 INFO - PROCESS | 1644 | 06:57:41 INFO - PROCESS | 1644 | ************************* 06:57:41 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 06:57:41 INFO - PROCESS | 1644 | 06:57:41 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:57:41 INFO - PROCESS | 1644 | Full stack: 06:57:41 INFO - PROCESS | 1644 | ************************* 06:58:59 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 06:59:00 INFO - PROCESS | 1644 | RSS: Main: 246947840 06:59:00 INFO - PROCESS | 1644 | 07:02:02 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 07:02:02 INFO - PROCESS | 1644 | RSS: Main: 254291968 07:02:02 INFO - PROCESS | 1644 | 07:05:04 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 07:05:05 INFO - PROCESS | 1644 | RSS: Main: 251346944 07:05:05 INFO - PROCESS | 1644 | 07:08:08 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 07:08:08 INFO - PROCESS | 1644 | RSS: Main: 254693376 07:08:08 INFO - PROCESS | 1644 | 07:11:11 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 07:11:11 INFO - PROCESS | 1644 | RSS: Main: 256225280 07:11:11 INFO - PROCESS | 1644 | 07:11:11 INFO - PROCESS | 1644 | __start_tp_report 07:11:11 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 07:11:11 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 07:11:11 INFO - PROCESS | 1644 | |i|pagename|runs| 07:11:11 INFO - PROCESS | 1644 | |0;dojo.html;2614.5293847107514;3361.865525378985;3382.424987752082;3390.7456940729467;3393.524906513088;5655.971720141399;5672.149177623359;5683.204351390808;5711.086226203808;5718.456746609066;4342.674299427547;5644.943550564493;5699.68651724156;5707.800226992056;5765;5149.382074151107;5162.716050617215;5242.606804489659;5256.080185967459;5287.286216360781;5184.507471790174;5196.558292545138;5246.973765131169;5249.26510288558;5297.205419187114;5091.541761241506;5127.974405118991;5141.280220769096;5221.973890130563;5234.973825130888;4959.82640607577;4966.850994470172;5003.674761140514;5018.498150184989;5041.344625198701;4956.900861982776;4956.975215123901;4963.801447942078;4991.475895031006;5022.648414610979;5305.814296499642;5399.163129714901;5454.754536045887;5526.364468086182;5554.805581804618;6132.325444201134;6161.784337548209;6202.596831205957;6254.437100660963;6286.277078135981;6231.127642130105;6244.718987645521;6248.3751624837605;6249.218847644044;6354.4281014708895;5816.709164541799;5829.912551311733;5852.005159122959;5862.824115276549;5892.852678682982;6114.724837382329;6133.946794548733;6202.503799696014;6222.471089957405;6239.438450539383;6118.265808103056;6189.81430557084;6195.2255968004;6203.47270482005;6206.410391012847;5338.252644629755;6128.417800308964;6267.467265282411;6272.1219029335925;6278.654673992883;6073.119397687399;6138.143176733788;6140.7189816757555;6142.498775697809;6243.75024999004;6244.406781355764;6244.687765611701;6262.1859158310035;6271.341509141565;6282.2147231596055;4974.0051989602225;4975.925361119514;4976.8755781105765;4977.800887964441;4987.127252730757;4387.254166791653;4399.978000109979;4404.55954404557;4476.283794592915;4502.752348620792;5757.942420575824;5804.390538993429;5855.414458554195;5885.61743486672;5910.911336329958;6215;6254.8123556293385;6257.655828929453;6272.717727702265;6278.497720182374;5649.180868774005;5720.85582883425;5742.799002034909;5766.740496677659;5803.564732644984;5440.4287549807495;5445.401005889349;5458.563314934796;5465.07093794056;5468.070428027245;6325.9051114233325;6347.619142851351;6347.635258419462;6364.618122912455;6457;4948.935978764583;6122.571420000735;6130.785422510191;6195.628262304276;6258.155149054819;5615.298087739033;5710.36730799798;6049.758009679564;6327.430531252209;6363.172787537591;5133.589312854888;6198.2252218472695;6205.410486003822;6217.782377616762;6219;6123.08153776937;6126.914660831567;6215.533834962486;6289.779857704958;6301.149344838388;6121;6161.383861613803;6162.106494558354;6191.133241346305;6198.752049917953;6045.304789949037;6150.630962142286;6158.907616385668;6173.81478555644;6206.472449841814;4538.886527836698;5755.165501002415;5908.025175845937;5948.40515948419;5973.671448070398;6116.396640261158;6132.264128304632;6138.754449821957;6185.134081228542;6209.161763162096;6023.417756222104;6029.366916473882;6051.425114614105;6067.211262535842;6070.089486577049 07:11:11 INFO - PROCESS | 1644 | |1;ext.html;498.12827551784375;503.91937290033604;521.0334828892405;545.6289722988366;550.1225545255317;38641.033974150625;41282;41388.34446622139;41458.585414145884;41488.755337339855;42399.36400953988;42740.78629606848;42831.78584107075;42849.7857510712;42949.14101717964;8492.745217643449;8713.3464990126;9258.444493330388;9478.952605236964;9504.287178461644;11302.095832333394;11601.129915256348;11620.6513804586;11725.296482211095;11733.823992640117;9819.26355523335;9828.508574571279;11492.023178029878;11510.424478776069;11538.192326537146;9205.769855753593;10502.264841461101;11458.598949036785;11497.195196336259;11536.65390038296;11414;11499.942500287445;11504.597339093092;11510.366929818856;11543.53825846965;41140.79429602863;41354.75935721933;41459;41530.75407737773;41812.954676133035;3900.960990390088;3916.8433262669746;3972.602739726033;3972.920541589181;4031.677465802729;25233;25643;30361.481925903838;30652.8467357664;30877.845610772027;3142.9528557071662;3225.1614580209316;3270.1661076425357;3305.652906444837;3397.7866356366712;449340;453325.7333713343;479089;482084;482108;54414.727926360116;66588.33411665919;67082;67139.66430167817;67382;64350.67824660847;70477.64761176161;70809.93785093227;70920.2907970924;71712;16703.582410439838;17410.21654025572;17411.216495257744;17414.216360263817;17441.651166976844;11483.023942964941;11969.64091077269;12266.570670026686;12896.48414063427;12944.546940857213;15724.584787369191;18852.34016809405;19036.42890713281;19094.904525477283;19220.327288545115;5862.325832529228;6829;6953.33943275388;6956.65216739161;7216.242294559101;857.9957100214459;867.3408209760502;867.5922316511229;876.1632640827994;906.619219927632;29210.41579168447;29772.553411698842;29931.551026734618;30339.696603033688;30509.084727458212;832.7051435018483;1005.4218824176128;1084.6041194963939;1097.9560817567367;1171.6777886081225;14903.627409314853;15402.22988850553;15460;15682.607934801721;15732.213389330716;616545;617196;620069;622126;624376;61672.38327616756;61845;61999.07001394983;62011.759764804454;62052.069218961755;1393.4983405973842;1434.5194359889344;1438.6619144501035;1441.4018182454374;1444.8916331275198;28330.150095497167;29090.563641545818;29193.562096568145;29600.407991839613;29820.403591928472;51579.74210128925;51679.483205168974;52043.95912081813;52199.73900130475;52258.73870630623;524557;527447;530278;533400;534383.3280833571;23962.40093997664;24581.13966011181;26874.596881046407;27006.459870802868;27079.323016924736;47802.04395912132;48257.75871120622;48476.51523484861;48743.75628121837;48984.75507622439;32615.347693046482;39118.60881391264;39133.804330978164;40585.39121913115;41152.1769564613;30316.5452518208;35941.562337506206;39539;40543.1891362177;41330;37191.62808371882;37975.2404951905;38616;38647;38728.419073713354;28603.85698071496;31106;33262.5010624836;35354.46968295426;35402.29195416129;22588.66117008279;24219.273421797374;24944.875275623504;25833.483330333664;25962 07:11:11 INFO - PROCESS | 1644 | |2;jquery.html;391.9843206271747;393.3588251150624;399.0920655508718;401.54424727933787;424.2088504938289;15708.921455392721;17116.91441542794;21059.894700526514;21061.68407473887;21754.912254387298;21221.151153953862;21655.45861353467;21745.456363590918;21761.34715958524;21920.903954802277;5554.86112847179;5918.2898052233795;5971;5998;6017.789377371793;5594.328680558338;5806.506446952014;5851.210086638313;5905.232319798421;5908.527317814586;5839.94880921434;5955.659976505295;5983.192269043689;5996.730147143389;6001.129836173752;5794.159846822229;5914.172015917775;5970.611910225864;5989.341172471025;5990.580659353847;5811.970940145315;5883.499902508292;5889.882202355929;5898.351181370046;5913.319968203668;25495;25558.616620750705;26225.475490490084;26254.6061809073;26710;2081.6669332906813;2427.017058091476;2539.593665013589;2597.7253747961863;2636.5386057440064;8138.837223255561;8788.209061184523;10242.743931401774;11574.6527604172;15725.292361843745;1797.739327797462;1800.4598620413954;1803.5491127218195;1811.873168878179;1836.8714190006706;126086.36956815248;131086;138020;138791.30604346914;139510;11219.775604487864;12142.939285303693;12421.37893105341;12468.937655311665;12566.560170393992;15925.442609508611;15994.920025400032;16025.19874006295;16037.037777733374;16059.839401606068;7626.8093297668;7638.732644357421;8710.209627329119;8968.910310896938;8985.595648195847;7111.146662400545;8734.65061397537;8741.606627701769;8864.822703546022;8872.955635221912;4793.736344501086;4824.372831531879;4849.466558678542;4864.343313652683;4934.037862616827;2004.9649605650277;2062.3812856143095;2064.298138632842;2064.318774804311;2065.514604068043;871.9738407847775;889.5552223888056;965.3869792681558;985.1872205430549;1005.648023191877;21605.243816466347;21690.6746398801;21797.782022179894;21878.12487500482;22090.558188836454;1029.3103620574084;1042.0461634435433;1042.2391654092555;1061.2836335473523;1069.1713921710582;10962.945185274022;11490.597829076109;11495.482703278372;11534.942325288488;11574.537018519333;544377.5562244406;586285;765944;784093;791043;140525;154530.68203976948;156587;160994;163793.18103409407;1052.9052385285208;1090.095220966596;1107.4905543449943;1110.4503270880805;1118.3122379736567;16134.515964521082;19982.50043748918;20586.897065514575;20629;20638.484037898572;54668;58118.709406454385;61630;62453;62648.373516264255;80935.57193284211;81980;82274.17725822904;82571.17428825634;84867.5756621213;8423.663053477792;8463.49219046859;8539.786505337417;8627.482351058956;8712.741239744699;10573.788524229627;12758.362081896053;12891.806622900851;13211.933940330237;13266.734665306833;9110.36227464071;10196.133328667147;10979.560817567208;11141.774404815626;11397.943010284895;8457.112003239818;10518.263721539419;10730.83903741429;10829.350238985686;10873.347599144077;10289.485525723834;10449.268551201647;11315.943420282845;11561.595344162914;11621.535138594361;6263.6241825490615;7221.31397517235;7560.470767046254;8330.875036874331;8553.486790792573;7861.60691965388;9043.790882309093;11012.229143959847;11174.66476005721;11331.830022549504 07:11:11 INFO - PROCESS | 1644 | |3;mootools.html;1210.9515619375225;1257.1953949472334;1433.4982756035383;1436.7270218658462;1451.1583281696614;7556.735514257002;7755.573443460607;8090.190980901914;8559.999999999993;8590.226879580836;7874.92125078749;8053;8203.95898020509;8311.459755115913;8445.91554084459;2099.88450635215;2331.137479132715;2332.1604222479896;2340.5903966805827;2341.613633750429;2085.3326935380683;2162.686410470481;2318.026428899866;2353.105819788478;2369.2655276864143;1886.5849513107164;2350.059976009593;2351.635496498037;2357.116081469449;2365.432296248905;2311.4452531392512;2318;2328.6274196128707;2339.8596084235;2341.590221711194;2306.826987975892;2346.7770561796774;2360.7521210273017;2382.0590866607768;2386.129062892049;8095.433319667625;8159.673613055471;8196.795080122985;8197.303229225523;8210.835783284361;5033.194688849803;5159.793608255665;5180.171172612364;5194.71429071401;5234.738263086869;3231.87072517099;3435.639257877937;3480.965190348115;3497.545319108525;3531.7174626029855;1151.3322273081592;1179.1038810503992;1232.340697726721;1232.5439587352648;1238.5541205166132;10830.891691083067;10966.06788422985;10992.010719035245;11005.504752286166;11027.83458248127;4011.959880401217;4529.320601909674;4663.27719203524;4668.5798278155125;4748.026654535812;9434.19809316202;9530;9558;9563.61745530171;9613.375130616627;5128.333316668809;5213.296205012351;5224.52979231871;5226.085435048852;5240.685558866404;4472.44094488189;4635.351050852883;4710.034442939189;4780.593649539758;4790.616750659939;3315.436375816117;3885.436611691289;3950.743201691881;4025.798710064485;4041.939370909439;1185.3776767197292;1228.9431089263226;1230.8769123087798;1295.2681734819796;1302.6547964789338;667.2160211751172;733.3729661139755;766.6933226709249;769.8575763483774;795.6300320351064;5100.4899510049445;5325.600579956519;5326.946730532645;5379.973100134474;5532.225488431623;946.9289803264645;959.8752162218867;963.3882484622316;974.206022092007;997.8652881860855;3654.3970244909824;3675.4854320395166;3680.705543556509;3771.9245615087543;3805.37211360128;9345.018773028734;9559.713208603753;9579.425234485952;9616;9647.179989700953;9594.280428967855;9600.951995240119;9609.951950240344;9611.990740972236;9637.325387222827;7254.96372518134;7572.659230334648;7574;7636.618169091635;7654.655540500691;5562.777488900399;5621.634593751393;5677.091665333692;5706.115552089433;5735.512481439125;8964.058773828914;9113.270938324786;9172.721554409929;9201.217896478875;9265.166135047879;7866.6066696666085;9239.491827949258;9305.488198149167;9316.114969077928;9502.334836561651;7043.886833949897;7253.3834624057545;7271.527350722186;7271.745488907863;7274.781756547312;7807.867859160277;7853.56805375687;7884;7918.524888506708;7947.324477419253;6085.4523092921845;7086.291370863079;7138.571685698875;7147.964260178665;7183.568985860865;6892.207396149507;6967.756128535477;7013.754518591825;7025.789226323219;7059.682314295972;6271.3728627136925;6389.2971773105855;6401.103845461546;6408.359164083555;6409.4231519163495;4789.784459699252;4847.3698419205275;4944.579710724629;4946.057096027857;5093.668911520739;4874.805007799649;4904.7302398367;4969.801207951641;5026.798928042837;5047.671901326402 07:11:11 INFO - PROCESS | 1644 | |4;prototype.html;294.9247941774847;356.8472545064356;366.4960679066284;371.97210209234316;374.3168717091309;5068.239764035397;5084.898302033962;5185.974070129644;5258.158694608864;5352;4666.556677115677;5240.973795131029;5393;5410.648307859987;5483.835484935448;3908.0816008238185;4270.423492828467;4306.397104405386;4306.5262821089655;4346.739195648255;3355.513450549669;4185.539590645027;4279.657627389802;4285.528591854909;4328.956710432887;4220.459532270715;4234.597713217239;4237.643953934742;4242.257604919127;4273.850415235467;3581.982090089559;4186.079062606222;4229.281022226229;4234.259004674185;4264.123785534355;3766.924661506782;3787.7273635818287;3809.257194847006;3809.6380843819793;3809.714271429626;3889.2221555688975;4270.765107919063;4389.648828093745;5130.97434512825;5369.812056578001;4005.7796821174825;4215.78921053946;4937.23472861707;4955.653104282702;4975.129352363322;4590.931136032962;5427.670220795931;5796.956547821391;5839.620424672383;5855.941440585582;4098.426220329156;4135.400366946806;4158.792060396999;4169.478815148106;4192.056787222881;5006.774695138727;5206.813138481809;6105.412592725879;6110.908336374958;6208.906866397007;3954.7937878947196;3980.3432433648704;4262.488501379854;4280.700350975402;4292.819474644498;3815;4126.773027483518;4297.3768803523935;4368.7815609219415;4374.600127959023;4844.103840789465;4875.756212189376;4957.504249575014;5017.774200161001;5022.171341728574;5897.908886855946;5909.349971503131;5913.70431478433;6000.649853782951;6015.3383127855905;1101.8292164714321;1266.5250531050856;1273.9681507962189;1301.0762358725221;1322.5172811923771;757.6325482141157;871.5772850167664;885.2696525366598;886.8536691445839;888.8533391990378;4206.85276015338;4578.771061446914;5132.2534549778875;5140.203268493315;5193.818216362485;5023.497650234947;5102.188723003281;5286.97356513215;5659.462351076641;5697.344805347355;4983.551480366785;5126.461721519261;5142.357205349332;5161.071007218737;5194;2588.818782685194;2923.327634644044;2991.536311871663;3013.8643761030808;3025.6217972753407;4868.537488938545;5222.712750798667;5500.22996780451;5753.612250915907;5893.640624843883;4590.449146102423;4795.712257264575;5381.434949330264;5457.808976685797;5890.381509941394;5876.619968912597;5920.407959204132;5943.613665111754;5968.761249550056;5995.520358371233;5073.340465739578;5204.386640141568;5665.745041473061;5719.656820590778;5766.4233576642;4256.4679415073115;4963.205887058053;5445.83662490126;5928.970355148369;5951.732172052181;5624;5702.175303902712;5819.301683797971;6059.72731227087;6241.751649669994;3632.8365223565534;3774.5659249185596;3954.1893911747447;3996.420519024783;4012.813016905735;5927.940720592911;5939.732712027882;5940.485176280194;5949;5977.953858074907;5138.820141295187;5255.237990491434;5762.4764559822015;5815.546113471632;5883.588148829541;4779.518349311725;4978.9130738942085;5288.338957630296;5624.718764061862;5769.192313076089;4648.7443190624845;4887.560119589112;4981.501849814989;5558.803103656875;5625.521830644441;4535.297028960516;4668.813247470063;5325.573954083587;5772.94227057724;5783.438471612725;4711.246200607886;4934.703917764946;5623.578231632562;5626.127950167731;5650.971745141248 07:11:11 INFO - PROCESS | 1644 | |5;yui.html;678.3149019490313;694.17046629278;743.4089898530667;771.5949126708477;828.3869936247177;14078.788818167723;15043.398264069452;15068.342482326963;15074.095554266753;15074.39702411905;13614.319284035784;13735.725285494284;13762.174269543835;13779.17324960503;13780.379882905268;652.8890088684911;822.1860358245339;838.534613289624;838.6016642094986;839.6599377252193;680.5508364479444;796.0765512006074;838.4172999765166;854.679495189304;857.7169534053747;887.5784002598779;889.9911000889973;892.2103938014872;893.5085702863431;895.1987970766161;860.2515811244195;880.2869675562814;882.4131952251745;882.761654353321;883.1698203688544;662.9370209830058;665.3446355339986;666.4335314982275;666.8432918264205;666.9199696036458;13852.238126903016;13908.374123164482;13910.304484775821;14225.928870355685;15023.09861408319;13796;14037.929810350883;14111.234882240578;14125.717485650332;14261;11522.76954460906;11531.07751379896;11658.94170529142;11690.941545292304;11913.523459061627;3533.752637315389;3645.446165739941;3648.2521083177885;3650.1787097903025;3816.9884980480197;106181;108713.45643271891;109141;110228;111621;10702.732431689114;10800.45997700112;10813.648813975757;11002.724931876766;11088.168387370979;21303.254386096723;21665.67501487479;21680;21713.457163571038;21756.238531651317;7454.084990129003;7468.468066322486;7472.925270747223;7535.02044734192;7665.233476652402;3753.512043434336;3980.3631418972827;4125.257453658311;4137.834486620562;4163.479565054368;3366.612839523486;3421.2815308784934;3425.8972230833115;3430.2624935638955;3432.9485057724155;562.7242651101013;618.9319174890759;637.1366797988659;639.1690801957436;650.7982525417136;602.9035354343372;615.1480199923138;628.9308176100625;657.8717150155769;659.2418718473698;9009.504477253682;9566.464886805003;10405.42770147634;10680.252382333318;10806.945965270123;519.5246349590158;551.3383939272809;568.3605943313772;575.2722805650855;579.8289504596154;4595.264757638762;4918.483559226302;4922.237053256751;4935.383077115361;4935.703857768545;88616.5569172163;91737;95858.52070739602;99326.5033674827;102738.48630756947;62796;72883.54232915418;75795.86306205411;79491.60254198808;84911;74473.88289175667;78475.21524784679;80908;81808;82340.58829705932;10583.629572965217;10674.573017079541;10764.677059688222;10899.945500272448;10945.781084378426;44857.55142448534;50286.74856625694;50573.74713126411;51136.48863511317;51574;60574.78850423055;62832.37167628265;66316.6684166576;66646;68197;10234.334768240338;11623;12167.513299467922;12395.442205100611;12680.809787853374;14017.929910350384;14347.067440616327;14516.782248266496;14537.491187808377;14544.927275363556;12982.89203873603;13359.265240411481;13393.933030334785;13511.662208444868;13854.722905542034;10381.784659661766;12320;13251.80122298147;13390.196588204739;13485.123466974615;12365.258084514959;13251;13343.599692009255;13349.065565410716;13408.530701425794;10660.307080039773;10822.567097316334;11483.712907177387;11633.263879520024;11703.23928944616;10154.39073655583;10852.837207442053;11028.944855275673;11056.060234880126;11064.39145846954 07:11:11 INFO - PROCESS | 1644 | __end_tp_report 07:11:11 INFO - PROCESS | 1644 | __start_cc_report 07:11:11 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,391 07:11:11 INFO - PROCESS | 1644 | __end_cc_report 07:11:11 INFO - PROCESS | 1644 | __startTimestamp1488467471819__endTimestamp 07:11:11 INFO - PROCESS | 1644 | 07:11:11 INFO - PROCESS | 1644 | ------- Summary: start ------- 07:11:11 INFO - PROCESS | 1644 | Number of tests: 6 07:11:11 INFO - PROCESS | 1644 | 07:11:11 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5694.30 Median:5910.91 stddev:683.28 (11.6%) stddev-sans-first:641.25 07:11:11 INFO - PROCESS | 1644 | Values: 2614.5 3361.9 3382.4 3390.7 3393.5 5656.0 5672.1 5683.2 5711.1 5718.5 4342.7 5644.9 5699.7 5707.8 5765.0 5149.4 5162.7 5242.6 5256.1 5287.3 5184.5 5196.6 5247.0 5249.3 5297.2 5091.5 5128.0 5141.3 5222.0 5235.0 4959.8 4966.9 5003.7 5018.5 5041.3 4956.9 4957.0 4963.8 4991.5 5022.6 5305.8 5399.2 5454.8 5526.4 5554.8 6132.3 6161.8 6202.6 6254.4 6286.3 6231.1 6244.7 6248.4 6249.2 6354.4 5816.7 5829.9 5852.0 5862.8 5892.9 6114.7 6133.9 6202.5 6222.5 6239.4 6118.3 6189.8 6195.2 6203.5 6206.4 5338.3 6128.4 6267.5 6272.1 6278.7 6073.1 6138.1 6140.7 6142.5 6243.8 6244.4 6244.7 6262.2 6271.3 6282.2 4974.0 4975.9 4976.9 4977.8 4987.1 4387.3 4400.0 4404.6 4476.3 4502.8 5757.9 5804.4 5855.4 5885.6 5910.9 6215.0 6254.8 6257.7 6272.7 6278.5 5649.2 5720.9 5742.8 5766.7 5803.6 5440.4 5445.4 5458.6 5465.1 5468.1 6325.9 6347.6 6347.6 6364.6 6457.0 4948.9 6122.6 6130.8 6195.6 6258.2 5615.3 5710.4 6049.8 6327.4 6363.2 5133.6 6198.2 6205.4 6217.8 6219.0 6123.1 6126.9 6215.5 6289.8 6301.1 6121.0 6161.4 6162.1 6191.1 6198.8 6045.3 6150.6 6158.9 6173.8 6206.5 4538.9 5755.2 5908.0 5948.4 5973.7 6116.4 6132.3 6138.8 6185.1 6209.2 6023.4 6029.4 6051.4 6067.2 6070.1 07:11:11 INFO - PROCESS | 1644 | 07:11:11 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:68369.75 Median:21509.78 stddev:144898.45 (673.6%) stddev-sans-first:145215.32 07:11:11 INFO - PROCESS | 1644 | Values: 498.1 503.9 521.0 545.6 550.1 38641.0 41282.0 41388.3 41458.6 41488.8 42399.4 42740.8 42831.8 42849.8 42949.1 8492.7 8713.3 9258.4 9479.0 9504.3 11302.1 11601.1 11620.7 11725.3 11733.8 9819.3 9828.5 11492.0 11510.4 11538.2 9205.8 10502.3 11458.6 11497.2 11536.7 11414.0 11499.9 11504.6 11510.4 11543.5 41140.8 41354.8 41459.0 41530.8 41813.0 3901.0 3916.8 3972.6 3972.9 4031.7 25233.0 25643.0 30361.5 30652.8 30877.8 3143.0 3225.2 3270.2 3305.7 3397.8 449340.0 453325.7 479089.0 482084.0 482108.0 54414.7 66588.3 67082.0 67139.7 67382.0 64350.7 70477.6 70809.9 70920.3 71712.0 16703.6 17410.2 17411.2 17414.2 17441.7 11483.0 11969.6 12266.6 12896.5 12944.5 15724.6 18852.3 19036.4 19094.9 19220.3 5862.3 6829.0 6953.3 6956.7 7216.2 858.0 867.3 867.6 876.2 906.6 29210.4 29772.6 29931.6 30339.7 30509.1 832.7 1005.4 1084.6 1098.0 1171.7 14903.6 15402.2 15460.0 15682.6 15732.2 616545.0 617196.0 620069.0 622126.0 624376.0 61672.4 61845.0 61999.1 62011.8 62052.1 1393.5 1434.5 1438.7 1441.4 1444.9 28330.2 29090.6 29193.6 29600.4 29820.4 51579.7 51679.5 52044.0 52199.7 52258.7 524557.0 527447.0 530278.0 533400.0 534383.3 23962.4 24581.1 26874.6 27006.5 27079.3 47802.0 48257.8 48476.5 48743.8 48984.8 32615.3 39118.6 39133.8 40585.4 41152.2 30316.5 35941.6 39539.0 40543.2 41330.0 37191.6 37975.2 38616.0 38647.0 38728.4 28603.9 31106.0 33262.5 35354.5 35402.3 22588.7 24219.3 24944.9 25833.5 25962.0 07:11:11 INFO - PROCESS | 1644 | 07:11:11 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:39370.60 Median:12258.79 stddev:117535.38 (958.8%) stddev-sans-first:117828.65 07:11:11 INFO - PROCESS | 1644 | Values: 392.0 393.4 399.1 401.5 424.2 15708.9 17116.9 21059.9 21061.7 21754.9 21221.2 21655.5 21745.5 21761.3 21920.9 5554.9 5918.3 5971.0 5998.0 6017.8 5594.3 5806.5 5851.2 5905.2 5908.5 5839.9 5955.7 5983.2 5996.7 6001.1 5794.2 5914.2 5970.6 5989.3 5990.6 5812.0 5883.5 5889.9 5898.4 5913.3 25495.0 25558.6 26225.5 26254.6 26710.0 2081.7 2427.0 2539.6 2597.7 2636.5 8138.8 8788.2 10242.7 11574.7 15725.3 1797.7 1800.5 1803.5 1811.9 1836.9 126086.4 131086.0 138020.0 138791.3 139510.0 11219.8 12142.9 12421.4 12468.9 12566.6 15925.4 15994.9 16025.2 16037.0 16059.8 7626.8 7638.7 8710.2 8968.9 8985.6 7111.1 8734.7 8741.6 8864.8 8873.0 4793.7 4824.4 4849.5 4864.3 4934.0 2005.0 2062.4 2064.3 2064.3 2065.5 872.0 889.6 965.4 985.2 1005.6 21605.2 21690.7 21797.8 21878.1 22090.6 1029.3 1042.0 1042.2 1061.3 1069.2 10962.9 11490.6 11495.5 11534.9 11574.5 544377.6 586285.0 765944.0 784093.0 791043.0 140525.0 154530.7 156587.0 160994.0 163793.2 1052.9 1090.1 1107.5 1110.5 1118.3 16134.5 19982.5 20586.9 20629.0 20638.5 54668.0 58118.7 61630.0 62453.0 62648.4 80935.6 81980.0 82274.2 82571.2 84867.6 8423.7 8463.5 8539.8 8627.5 8712.7 10573.8 12758.4 12891.8 13211.9 13266.7 9110.4 10196.1 10979.6 11141.8 11397.9 8457.1 10518.3 10730.8 10829.4 10873.3 10289.5 10449.3 11315.9 11561.6 11621.5 6263.6 7221.3 7560.5 8330.9 8553.5 7861.6 9043.8 11012.2 11174.7 11331.8 07:11:11 INFO - PROCESS | 1644 | 07:11:11 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5381.25 Median:5283.14 stddev:2921.74 (55.3%) stddev-sans-first:2913.12 07:11:11 INFO - PROCESS | 1644 | Values: 1211.0 1257.2 1433.5 1436.7 1451.2 7556.7 7755.6 8090.2 8560.0 8590.2 7874.9 8053.0 8204.0 8311.5 8445.9 2099.9 2331.1 2332.2 2340.6 2341.6 2085.3 2162.7 2318.0 2353.1 2369.3 1886.6 2350.1 2351.6 2357.1 2365.4 2311.4 2318.0 2328.6 2339.9 2341.6 2306.8 2346.8 2360.8 2382.1 2386.1 8095.4 8159.7 8196.8 8197.3 8210.8 5033.2 5159.8 5180.2 5194.7 5234.7 3231.9 3435.6 3481.0 3497.5 3531.7 1151.3 1179.1 1232.3 1232.5 1238.6 10830.9 10966.1 10992.0 11005.5 11027.8 4012.0 4529.3 4663.3 4668.6 4748.0 9434.2 9530.0 9558.0 9563.6 9613.4 5128.3 5213.3 5224.5 5226.1 5240.7 4472.4 4635.4 4710.0 4780.6 4790.6 3315.4 3885.4 3950.7 4025.8 4041.9 1185.4 1228.9 1230.9 1295.3 1302.7 667.2 733.4 766.7 769.9 795.6 5100.5 5325.6 5326.9 5380.0 5532.2 946.9 959.9 963.4 974.2 997.9 3654.4 3675.5 3680.7 3771.9 3805.4 9345.0 9559.7 9579.4 9616.0 9647.2 9594.3 9601.0 9610.0 9612.0 9637.3 7255.0 7572.7 7574.0 7636.6 7654.7 5562.8 5621.6 5677.1 5706.1 5735.5 8964.1 9113.3 9172.7 9201.2 9265.2 7866.6 9239.5 9305.5 9316.1 9502.3 7043.9 7253.4 7271.5 7271.7 7274.8 7807.9 7853.6 7884.0 7918.5 7947.3 6085.5 7086.3 7138.6 7148.0 7183.6 6892.2 6967.8 7013.8 7025.8 7059.7 6271.4 6389.3 6401.1 6408.4 6409.4 4789.8 4847.4 4944.6 4946.1 5093.7 4874.8 4904.7 4969.8 5026.8 5047.7 07:11:11 INFO - PROCESS | 1644 | 07:11:11 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4585.39 Median:5012.27 stddev:1377.83 (27.5%) stddev-sans-first:1343.54 07:11:11 INFO - PROCESS | 1644 | Values: 294.9 356.8 366.5 372.0 374.3 5068.2 5084.9 5186.0 5258.2 5352.0 4666.6 5241.0 5393.0 5410.6 5483.8 3908.1 4270.4 4306.4 4306.5 4346.7 3355.5 4185.5 4279.7 4285.5 4329.0 4220.5 4234.6 4237.6 4242.3 4273.9 3582.0 4186.1 4229.3 4234.3 4264.1 3766.9 3787.7 3809.3 3809.6 3809.7 3889.2 4270.8 4389.6 5131.0 5369.8 4005.8 4215.8 4937.2 4955.7 4975.1 4590.9 5427.7 5797.0 5839.6 5855.9 4098.4 4135.4 4158.8 4169.5 4192.1 5006.8 5206.8 6105.4 6110.9 6208.9 3954.8 3980.3 4262.5 4280.7 4292.8 3815.0 4126.8 4297.4 4368.8 4374.6 4844.1 4875.8 4957.5 5017.8 5022.2 5897.9 5909.3 5913.7 6000.6 6015.3 1101.8 1266.5 1274.0 1301.1 1322.5 757.6 871.6 885.3 886.9 888.9 4206.9 4578.8 5132.3 5140.2 5193.8 5023.5 5102.2 5287.0 5659.5 5697.3 4983.6 5126.5 5142.4 5161.1 5194.0 2588.8 2923.3 2991.5 3013.9 3025.6 4868.5 5222.7 5500.2 5753.6 5893.6 4590.4 4795.7 5381.4 5457.8 5890.4 5876.6 5920.4 5943.6 5968.8 5995.5 5073.3 5204.4 5665.7 5719.7 5766.4 4256.5 4963.2 5445.8 5929.0 5951.7 5624.0 5702.2 5819.3 6059.7 6241.8 3632.8 3774.6 3954.2 3996.4 4012.8 5927.9 5939.7 5940.5 5949.0 5978.0 5138.8 5255.2 5762.5 5815.5 5883.6 4779.5 4978.9 5288.3 5624.7 5769.2 4648.7 4887.6 4981.5 5558.8 5625.5 4535.3 4668.8 5325.6 5772.9 5783.4 4711.2 4934.7 5623.6 5626.1 5651.0 07:11:11 INFO - PROCESS | 1644 | 07:11:11 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:19822.62 Median:3646.85 stddev:28304.09 (776.1%) stddev-sans-first:28346.99 07:11:11 INFO - PROCESS | 1644 | Values: 678.3 694.2 743.4 771.6 828.4 14078.8 15043.4 15068.3 15074.1 15074.4 13614.3 13735.7 13762.2 13779.2 13780.4 652.9 822.2 838.5 838.6 839.7 680.6 796.1 838.4 854.7 857.7 887.6 890.0 892.2 893.5 895.2 860.3 880.3 882.4 882.8 883.2 662.9 665.3 666.4 666.8 666.9 13852.2 13908.4 13910.3 14225.9 15023.1 13796.0 14037.9 14111.2 14125.7 14261.0 11522.8 11531.1 11658.9 11690.9 11913.5 3533.8 3645.4 3648.3 3650.2 3817.0 106181.0 108713.5 109141.0 110228.0 111621.0 10702.7 10800.5 10813.6 11002.7 11088.2 21303.3 21665.7 21680.0 21713.5 21756.2 7454.1 7468.5 7472.9 7535.0 7665.2 3753.5 3980.4 4125.3 4137.8 4163.5 3366.6 3421.3 3425.9 3430.3 3432.9 562.7 618.9 637.1 639.2 650.8 602.9 615.1 628.9 657.9 659.2 9009.5 9566.5 10405.4 10680.3 10806.9 519.5 551.3 568.4 575.3 579.8 4595.3 4918.5 4922.2 4935.4 4935.7 88616.6 91737.0 95858.5 99326.5 102738.5 62796.0 72883.5 75795.9 79491.6 84911.0 74473.9 78475.2 80908.0 81808.0 82340.6 10583.6 10674.6 10764.7 10899.9 10945.8 44857.6 50286.7 50573.7 51136.5 51574.0 60574.8 62832.4 66316.7 66646.0 68197.0 10234.3 11623.0 12167.5 12395.4 12680.8 14017.9 14347.1 14516.8 14537.5 14544.9 12982.9 13359.3 13393.9 13511.7 13854.7 10381.8 12320.0 13251.8 13390.2 13485.1 12365.3 13251.0 13343.6 13349.1 13408.5 10660.3 10822.6 11483.7 11633.3 11703.2 10154.4 10852.8 11028.9 11056.1 11064.4 07:11:11 INFO - PROCESS | 1644 | -------- Summary: end -------- 07:11:11 INFO - PROCESS | 1644 | 07:11:12 INFO - TEST-INFO | 1644: exit 0 07:11:12 INFO - TEST-OK | dromaeo_css | took 1115059ms 07:11:12 INFO - TEST-START | kraken 07:11:12 INFO - operating with platform_type : mac_ 07:11:12 INFO - Initialising browser for kraken test... 07:11:12 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/tmppFkJ0Q/profile http://localhost:49237/getInfo.html) 07:11:15 INFO - PROCESS | 1652 | 2017-03-02 07:11:15.363 plugin-container[1653:10139] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 07:11:15 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:11:15 INFO - PROCESS | 1652 | 2017-03-02 07:11:15.366 plugin-container[1653:10139] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 07:11:15 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:11:15 INFO - PROCESS | 1652 | 2017-03-02 07:11:15.366 plugin-container[1653:10139] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:11:15 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 07:11:15 INFO - PROCESS | 1652 | colorDepth:24 07:11:15 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 07:11:15 INFO - PROCESS | 1652 | __metrics 07:11:15 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 07:11:16 INFO - TEST-INFO | 1652: exit 0 07:11:16 INFO - Browser initialized. 07:11:16 INFO - Running cycle 1/1 for kraken test... 07:11:16 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/tmppFkJ0Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:11:18 INFO - PROCESS | 1654 | 2017-03-02 07:11:18.528 plugin-container[1655:10269] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 07:11:18 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:11:18 INFO - PROCESS | 1654 | 2017-03-02 07:11:18.529 plugin-container[1655:10269] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4427, name = 'com.apple.CFPasteboardClient' 07:11:18 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:11:18 INFO - PROCESS | 1654 | 2017-03-02 07:11:18.529 plugin-container[1655:10269] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:11:28 INFO - PROCESS | 1654 | RSS: Main: 218316800 07:11:28 INFO - PROCESS | 1654 | 07:11:31 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 07:11:32 INFO - PROCESS | 1654 | RSS: Main: 232722432 07:11:32 INFO - PROCESS | 1654 | 07:11:34 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 07:11:35 INFO - PROCESS | 1654 | RSS: Main: 240656384 07:11:35 INFO - PROCESS | 1654 | 07:11:38 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 07:11:39 INFO - PROCESS | 1654 | RSS: Main: 244400128 07:11:39 INFO - PROCESS | 1654 | 07:11:41 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 07:11:41 INFO - PROCESS | 1654 | RSS: Main: 244846592 07:11:41 INFO - PROCESS | 1654 | 07:11:43 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:11:44 INFO - PROCESS | 1654 | RSS: Main: 244768768 07:11:44 INFO - PROCESS | 1654 | 07:11:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 07:11:47 INFO - PROCESS | 1654 | RSS: Main: 250757120 07:11:47 INFO - PROCESS | 1654 | 07:11:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 07:11:51 INFO - PROCESS | 1654 | RSS: Main: 249688064 07:11:51 INFO - PROCESS | 1654 | 07:11:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 07:11:55 INFO - PROCESS | 1654 | RSS: Main: 249896960 07:11:55 INFO - PROCESS | 1654 | 07:11:57 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:11:57 INFO - PROCESS | 1654 | RSS: Main: 248524800 07:11:57 INFO - PROCESS | 1654 | 07:11:59 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:12:00 INFO - PROCESS | 1654 | RSS: Main: 248582144 07:12:00 INFO - PROCESS | 1654 | 07:12:02 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:12:03 INFO - PROCESS | 1654 | RSS: Main: 248598528 07:12:03 INFO - PROCESS | 1654 | 07:12:06 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:12:07 INFO - PROCESS | 1654 | RSS: Main: 248922112 07:12:07 INFO - PROCESS | 1654 | 07:12:10 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:12:10 INFO - PROCESS | 1654 | RSS: Main: 249012224 07:12:10 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 07:12:12 INFO - PROCESS | 1654 | RSS: Main: 250880000 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | __start_tp_report 07:12:12 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 07:12:12 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 07:12:12 INFO - PROCESS | 1654 | |i|pagename|runs| 07:12:12 INFO - PROCESS | 1654 | |0;ai-astar;105;118;117;117;116;115;119;116;117;118 07:12:12 INFO - PROCESS | 1654 | |1;audio-beat-detection;135;110;113;146;114;114;113;111;131;117 07:12:12 INFO - PROCESS | 1654 | |2;audio-dft;196;201;193;201;198;194;196;200;200;197 07:12:12 INFO - PROCESS | 1654 | |3;audio-fft;83;58;58;86;61;62;76;80;97;83 07:12:12 INFO - PROCESS | 1654 | |4;audio-oscillator;88;71;86;73;61;74;104;69;91;107 07:12:12 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;76;82;92;73;136;133;130;88;90;87 07:12:12 INFO - PROCESS | 1654 | |6;imaging-darkroom;107;110;91;146;89;137;156;122;155;151 07:12:12 INFO - PROCESS | 1654 | |7;imaging-desaturate;92;90;93;74;144;148;90;92;95;94 07:12:12 INFO - PROCESS | 1654 | |8;json-parse-financial;59;59;60;63;62;64;62;75;79;64 07:12:12 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;67;78;68;72;98;56;69;97;104;102 07:12:12 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;87;90;107;106;74;105;109;103;105;85 07:12:12 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;136;138;157;147;162;162;154;159;192;128 07:12:12 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;168;170;169;173;200;190;164;172;170;168 07:12:12 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;69;67;68;69;84;87;87;87;90;66 07:12:12 INFO - PROCESS | 1654 | __end_tp_report 07:12:12 INFO - PROCESS | 1654 | __start_cc_report 07:12:12 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,966 07:12:12 INFO - PROCESS | 1654 | __end_cc_report 07:12:12 INFO - PROCESS | 1654 | __startTimestamp1488467532835__endTimestamp 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | ------- Summary: start ------- 07:12:12 INFO - PROCESS | 1654 | Number of tests: 14 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:115.80 Median:117.00 stddev:3.97 (3.4%) stddev-sans-first:1.22 07:12:12 INFO - PROCESS | 1654 | Values: 105.0 118.0 117.0 117.0 116.0 115.0 119.0 116.0 117.0 118.0 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:120.40 Median:115.50 stddev:12.38 (10.7%) stddev-sans-first:11.96 07:12:12 INFO - PROCESS | 1654 | Values: 135.0 110.0 113.0 146.0 114.0 114.0 113.0 111.0 131.0 117.0 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:197.60 Median:199.00 stddev:2.88 (1.4%) stddev-sans-first:2.99 07:12:12 INFO - PROCESS | 1654 | Values: 196.0 201.0 193.0 201.0 198.0 194.0 196.0 200.0 200.0 197.0 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:74.40 Median:81.50 stddev:13.74 (16.9%) stddev-sans-first:14.21 07:12:12 INFO - PROCESS | 1654 | Values: 83.0 58.0 58.0 86.0 61.0 62.0 76.0 80.0 97.0 83.0 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:82.40 Median:73.50 stddev:15.33 (20.9%) stddev-sans-first:16.13 07:12:12 INFO - PROCESS | 1654 | Values: 88.0 71.0 86.0 73.0 61.0 74.0 104.0 69.0 91.0 107.0 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:98.70 Median:84.50 stddev:24.44 (28.9%) stddev-sans-first:24.50 07:12:12 INFO - PROCESS | 1654 | Values: 76.0 82.0 92.0 73.0 136.0 133.0 130.0 88.0 90.0 87.0 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:126.40 Median:153.00 stddev:26.06 (17.0%) stddev-sans-first:26.68 07:12:12 INFO - PROCESS | 1654 | Values: 107.0 110.0 91.0 146.0 89.0 137.0 156.0 122.0 155.0 151.0 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:101.20 Median:92.00 stddev:24.36 (26.5%) stddev-sans-first:25.61 07:12:12 INFO - PROCESS | 1654 | Values: 92.0 90.0 93.0 74.0 144.0 148.0 90.0 92.0 95.0 94.0 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:63.50 stddev:6.80 (10.7%) stddev-sans-first:6.89 07:12:12 INFO - PROCESS | 1654 | Values: 59.0 59.0 60.0 63.0 62.0 64.0 62.0 75.0 79.0 64.0 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:81.10 Median:70.50 stddev:17.44 (24.7%) stddev-sans-first:17.74 07:12:12 INFO - PROCESS | 1654 | Values: 67.0 78.0 68.0 72.0 98.0 56.0 69.0 97.0 104.0 102.0 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:97.10 Median:91.50 stddev:12.07 (13.2%) stddev-sans-first:12.23 07:12:12 INFO - PROCESS | 1654 | Values: 87.0 90.0 107.0 106.0 74.0 105.0 109.0 103.0 105.0 85.0 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:158.00 stddev:17.98 (11.4%) stddev-sans-first:17.92 07:12:12 INFO - PROCESS | 1654 | Values: 136.0 138.0 157.0 147.0 162.0 162.0 154.0 159.0 192.0 128.0 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.40 Median:171.00 stddev:11.37 (6.7%) stddev-sans-first:11.83 07:12:12 INFO - PROCESS | 1654 | Values: 168.0 170.0 169.0 173.0 200.0 190.0 164.0 172.0 170.0 168.0 07:12:12 INFO - PROCESS | 1654 | 07:12:12 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.40 Median:85.50 stddev:10.25 (12.0%) stddev-sans-first:10.42 07:12:12 INFO - PROCESS | 1654 | Values: 69.0 67.0 68.0 69.0 84.0 87.0 87.0 87.0 90.0 66.0 07:12:12 INFO - PROCESS | 1654 | -------- Summary: end -------- 07:12:12 INFO - PROCESS | 1654 | 07:12:13 INFO - TEST-INFO | 1654: exit 0 07:12:13 INFO - TEST-OK | kraken | took 61414ms 07:12:13 INFO - SUITE-END | took 1176s 07:12:13 INFO - Completed test suite (00:19:36) 07:12:13 INFO - javascript benchmark 07:12:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7294.353806471407, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2614.5293847107514, 3361.865525378985, 3382.424987752082, 3390.7456940729467, 3393.524906513088, 5655.971720141399, 5672.149177623359, 5683.204351390808, 5711.086226203808, 5718.456746609066, 4342.674299427547, 5644.943550564493, 5699.68651724156, 5707.800226992056, 5765.0, 5149.382074151107, 5162.716050617215, 5242.606804489659, 5256.080185967459, 5287.286216360781, 5184.507471790174, 5196.558292545138, 5246.973765131169, 5249.26510288558, 5297.205419187114, 5091.541761241506, 5127.974405118991, 5141.280220769096, 5221.973890130563, 5234.973825130888, 4959.82640607577, 4966.850994470172, 5003.674761140514, 5018.498150184989, 5041.344625198701, 4956.900861982776, 4956.975215123901, 4963.801447942078, 4991.475895031006, 5022.648414610979, 5305.814296499642, 5399.163129714901, 5454.754536045887, 5526.364468086182, 5554.805581804618, 6132.325444201134, 6161.784337548209, 6202.596831205957, 6254.437100660963, 6286.277078135981, 6231.127642130105, 6244.718987645521, 6248.3751624837605, 6249.218847644044, 6354.4281014708895, 5816.709164541799, 5829.912551311733, 5852.005159122959, 5862.824115276549, 5892.852678682982, 6114.724837382329, 6133.946794548733, 6202.503799696014, 6222.471089957405, 6239.438450539383, 6118.265808103056, 6189.81430557084, 6195.2255968004, 6203.47270482005, 6206.410391012847, 5338.252644629755, 6128.417800308964, 6267.467265282411, 6272.1219029335925, 6278.654673992883, 6073.119397687399, 6138.143176733788, 6140.7189816757555, 6142.498775697809, 6243.75024999004, 6244.406781355764, 6244.687765611701, 6262.1859158310035, 6271.341509141565, 6282.2147231596055, 4974.0051989602225, 4975.925361119514, 4976.8755781105765, 4977.800887964441, 4987.127252730757, 4387.254166791653, 4399.978000109979, 4404.55954404557, 4476.283794592915, 4502.752348620792, 5757.942420575824, 5804.390538993429, 5855.414458554195, 5885.61743486672, 5910.911336329958, 6215.0, 6254.8123556293385, 6257.655828929453, 6272.717727702265, 6278.497720182374, 5649.180868774005, 5720.85582883425, 5742.799002034909, 5766.740496677659, 5803.564732644984, 5440.4287549807495, 5445.401005889349, 5458.563314934796, 5465.07093794056, 5468.070428027245, 6325.9051114233325, 6347.619142851351, 6347.635258419462, 6364.618122912455, 6457.0, 4948.935978764583, 6122.571420000735, 6130.785422510191, 6195.628262304276, 6258.155149054819, 5615.298087739033, 5710.36730799798, 6049.758009679564, 6327.430531252209, 6363.172787537591, 5133.589312854888, 6198.2252218472695, 6205.410486003822, 6217.782377616762, 6219.0, 6123.08153776937, 6126.914660831567, 6215.533834962486, 6289.779857704958, 6301.149344838388, 6121.0, 6161.383861613803, 6162.106494558354, 6191.133241346305, 6198.752049917953, 6045.304789949037, 6150.630962142286, 6158.907616385668, 6173.81478555644, 6206.472449841814, 4538.886527836698, 5755.165501002415, 5908.025175845937, 5948.40515948419, 5973.671448070398, 6116.396640261158, 6132.264128304632, 6138.754449821957, 6185.134081228542, 6209.161763162096, 6023.417756222104, 6029.366916473882, 6051.425114614105, 6067.211262535842, 6070.089486577049], "value": 5649.761287341012, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.12827551784375, 503.91937290033604, 521.0334828892405, 545.6289722988366, 550.1225545255317, 38641.033974150625, 41282.0, 41388.34446622139, 41458.585414145884, 41488.755337339855, 42399.36400953988, 42740.78629606848, 42831.78584107075, 42849.7857510712, 42949.14101717964, 8492.745217643449, 8713.3464990126, 9258.444493330388, 9478.952605236964, 9504.287178461644, 11302.095832333394, 11601.129915256348, 11620.6513804586, 11725.296482211095, 11733.823992640117, 9819.26355523335, 9828.508574571279, 11492.023178029878, 11510.424478776069, 11538.192326537146, 9205.769855753593, 10502.264841461101, 11458.598949036785, 11497.195196336259, 11536.65390038296, 11414.0, 11499.942500287445, 11504.597339093092, 11510.366929818856, 11543.53825846965, 41140.79429602863, 41354.75935721933, 41459.0, 41530.75407737773, 41812.954676133035, 3900.960990390088, 3916.8433262669746, 3972.602739726033, 3972.920541589181, 4031.677465802729, 25233.0, 25643.0, 30361.481925903838, 30652.8467357664, 30877.845610772027, 3142.9528557071662, 3225.1614580209316, 3270.1661076425357, 3305.652906444837, 3397.7866356366712, 449340.0, 453325.7333713343, 479089.0, 482084.0, 482108.0, 54414.727926360116, 66588.33411665919, 67082.0, 67139.66430167817, 67382.0, 64350.67824660847, 70477.64761176161, 70809.93785093227, 70920.2907970924, 71712.0, 16703.582410439838, 17410.21654025572, 17411.216495257744, 17414.216360263817, 17441.651166976844, 11483.023942964941, 11969.64091077269, 12266.570670026686, 12896.48414063427, 12944.546940857213, 15724.584787369191, 18852.34016809405, 19036.42890713281, 19094.904525477283, 19220.327288545115, 5862.325832529228, 6829.0, 6953.33943275388, 6956.65216739161, 7216.242294559101, 857.9957100214459, 867.3408209760502, 867.5922316511229, 876.1632640827994, 906.619219927632, 29210.41579168447, 29772.553411698842, 29931.551026734618, 30339.696603033688, 30509.084727458212, 832.7051435018483, 1005.4218824176128, 1084.6041194963939, 1097.9560817567367, 1171.6777886081225, 14903.627409314853, 15402.22988850553, 15460.0, 15682.607934801721, 15732.213389330716, 616545.0, 617196.0, 620069.0, 622126.0, 624376.0, 61672.38327616756, 61845.0, 61999.07001394983, 62011.759764804454, 62052.069218961755, 1393.4983405973842, 1434.5194359889344, 1438.6619144501035, 1441.4018182454374, 1444.8916331275198, 28330.150095497167, 29090.563641545818, 29193.562096568145, 29600.407991839613, 29820.403591928472, 51579.74210128925, 51679.483205168974, 52043.95912081813, 52199.73900130475, 52258.73870630623, 524557.0, 527447.0, 530278.0, 533400.0, 534383.3280833571, 23962.40093997664, 24581.13966011181, 26874.596881046407, 27006.459870802868, 27079.323016924736, 47802.04395912132, 48257.75871120622, 48476.51523484861, 48743.75628121837, 48984.75507622439, 32615.347693046482, 39118.60881391264, 39133.804330978164, 40585.39121913115, 41152.1769564613, 30316.5452518208, 35941.562337506206, 39539.0, 40543.1891362177, 41330.0, 37191.62808371882, 37975.2404951905, 38616.0, 38647.0, 38728.419073713354, 28603.85698071496, 31106.0, 33262.5010624836, 35354.46968295426, 35402.29195416129, 22588.66117008279, 24219.273421797374, 24944.875275623504, 25833.483330333664, 25962.0], "value": 20295.21144055925, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.9843206271747, 393.3588251150624, 399.0920655508718, 401.54424727933787, 424.2088504938289, 15708.921455392721, 17116.91441542794, 21059.894700526514, 21061.68407473887, 21754.912254387298, 21221.151153953862, 21655.45861353467, 21745.456363590918, 21761.34715958524, 21920.903954802277, 5554.86112847179, 5918.2898052233795, 5971.0, 5998.0, 6017.789377371793, 5594.328680558338, 5806.506446952014, 5851.210086638313, 5905.232319798421, 5908.527317814586, 5839.94880921434, 5955.659976505295, 5983.192269043689, 5996.730147143389, 6001.129836173752, 5794.159846822229, 5914.172015917775, 5970.611910225864, 5989.341172471025, 5990.580659353847, 5811.970940145315, 5883.499902508292, 5889.882202355929, 5898.351181370046, 5913.319968203668, 25495.0, 25558.616620750705, 26225.475490490084, 26254.6061809073, 26710.0, 2081.6669332906813, 2427.017058091476, 2539.593665013589, 2597.7253747961863, 2636.5386057440064, 8138.837223255561, 8788.209061184523, 10242.743931401774, 11574.6527604172, 15725.292361843745, 1797.739327797462, 1800.4598620413954, 1803.5491127218195, 1811.873168878179, 1836.8714190006706, 126086.36956815248, 131086.0, 138020.0, 138791.30604346914, 139510.0, 11219.775604487864, 12142.939285303693, 12421.37893105341, 12468.937655311665, 12566.560170393992, 15925.442609508611, 15994.920025400032, 16025.19874006295, 16037.037777733374, 16059.839401606068, 7626.8093297668, 7638.732644357421, 8710.209627329119, 8968.910310896938, 8985.595648195847, 7111.146662400545, 8734.65061397537, 8741.606627701769, 8864.822703546022, 8872.955635221912, 4793.736344501086, 4824.372831531879, 4849.466558678542, 4864.343313652683, 4934.037862616827, 2004.9649605650277, 2062.3812856143095, 2064.298138632842, 2064.318774804311, 2065.514604068043, 871.9738407847775, 889.5552223888056, 965.3869792681558, 985.1872205430549, 1005.648023191877, 21605.243816466347, 21690.6746398801, 21797.782022179894, 21878.12487500482, 22090.558188836454, 1029.3103620574084, 1042.0461634435433, 1042.2391654092555, 1061.2836335473523, 1069.1713921710582, 10962.945185274022, 11490.597829076109, 11495.482703278372, 11534.942325288488, 11574.537018519333, 544377.5562244406, 586285.0, 765944.0, 784093.0, 791043.0, 140525.0, 154530.68203976948, 156587.0, 160994.0, 163793.18103409407, 1052.9052385285208, 1090.095220966596, 1107.4905543449943, 1110.4503270880805, 1118.3122379736567, 16134.515964521082, 19982.50043748918, 20586.897065514575, 20629.0, 20638.484037898572, 54668.0, 58118.709406454385, 61630.0, 62453.0, 62648.373516264255, 80935.57193284211, 81980.0, 82274.17725822904, 82571.17428825634, 84867.5756621213, 8423.663053477792, 8463.49219046859, 8539.786505337417, 8627.482351058956, 8712.741239744699, 10573.788524229627, 12758.362081896053, 12891.806622900851, 13211.933940330237, 13266.734665306833, 9110.36227464071, 10196.133328667147, 10979.560817567208, 11141.774404815626, 11397.943010284895, 8457.112003239818, 10518.263721539419, 10730.83903741429, 10829.350238985686, 10873.347599144077, 10289.485525723834, 10449.268551201647, 11315.943420282845, 11561.595344162914, 11621.535138594361, 6263.6241825490615, 7221.31397517235, 7560.470767046254, 8330.875036874331, 8553.486790792573, 7861.60691965388, 9043.790882309093, 11012.229143959847, 11174.66476005721, 11331.830022549504], "value": 9932.28491817565, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1210.9515619375225, 1257.1953949472334, 1433.4982756035383, 1436.7270218658462, 1451.1583281696614, 7556.735514257002, 7755.573443460607, 8090.190980901914, 8559.999999999993, 8590.226879580836, 7874.92125078749, 8053.0, 8203.95898020509, 8311.459755115913, 8445.91554084459, 2099.88450635215, 2331.137479132715, 2332.1604222479896, 2340.5903966805827, 2341.613633750429, 2085.3326935380683, 2162.686410470481, 2318.026428899866, 2353.105819788478, 2369.2655276864143, 1886.5849513107164, 2350.059976009593, 2351.635496498037, 2357.116081469449, 2365.432296248905, 2311.4452531392512, 2318.0, 2328.6274196128707, 2339.8596084235, 2341.590221711194, 2306.826987975892, 2346.7770561796774, 2360.7521210273017, 2382.0590866607768, 2386.129062892049, 8095.433319667625, 8159.673613055471, 8196.795080122985, 8197.303229225523, 8210.835783284361, 5033.194688849803, 5159.793608255665, 5180.171172612364, 5194.71429071401, 5234.738263086869, 3231.87072517099, 3435.639257877937, 3480.965190348115, 3497.545319108525, 3531.7174626029855, 1151.3322273081592, 1179.1038810503992, 1232.340697726721, 1232.5439587352648, 1238.5541205166132, 10830.891691083067, 10966.06788422985, 10992.010719035245, 11005.504752286166, 11027.83458248127, 4011.959880401217, 4529.320601909674, 4663.27719203524, 4668.5798278155125, 4748.026654535812, 9434.19809316202, 9530.0, 9558.0, 9563.61745530171, 9613.375130616627, 5128.333316668809, 5213.296205012351, 5224.52979231871, 5226.085435048852, 5240.685558866404, 4472.44094488189, 4635.351050852883, 4710.034442939189, 4780.593649539758, 4790.616750659939, 3315.436375816117, 3885.436611691289, 3950.743201691881, 4025.798710064485, 4041.939370909439, 1185.3776767197292, 1228.9431089263226, 1230.8769123087798, 1295.2681734819796, 1302.6547964789338, 667.2160211751172, 733.3729661139755, 766.6933226709249, 769.8575763483774, 795.6300320351064, 5100.4899510049445, 5325.600579956519, 5326.946730532645, 5379.973100134474, 5532.225488431623, 946.9289803264645, 959.8752162218867, 963.3882484622316, 974.206022092007, 997.8652881860855, 3654.3970244909824, 3675.4854320395166, 3680.705543556509, 3771.9245615087543, 3805.37211360128, 9345.018773028734, 9559.713208603753, 9579.425234485952, 9616.0, 9647.179989700953, 9594.280428967855, 9600.951995240119, 9609.951950240344, 9611.990740972236, 9637.325387222827, 7254.96372518134, 7572.659230334648, 7574.0, 7636.618169091635, 7654.655540500691, 5562.777488900399, 5621.634593751393, 5677.091665333692, 5706.115552089433, 5735.512481439125, 8964.058773828914, 9113.270938324786, 9172.721554409929, 9201.217896478875, 9265.166135047879, 7866.6066696666085, 9239.491827949258, 9305.488198149167, 9316.114969077928, 9502.334836561651, 7043.886833949897, 7253.3834624057545, 7271.527350722186, 7271.745488907863, 7274.781756547312, 7807.867859160277, 7853.56805375687, 7884.0, 7918.524888506708, 7947.324477419253, 6085.4523092921845, 7086.291370863079, 7138.571685698875, 7147.964260178665, 7183.568985860865, 6892.207396149507, 6967.756128535477, 7013.754518591825, 7025.789226323219, 7059.682314295972, 6271.3728627136925, 6389.2971773105855, 6401.103845461546, 6408.359164083555, 6409.4231519163495, 4789.784459699252, 4847.3698419205275, 4944.579710724629, 4946.057096027857, 5093.668911520739, 4874.805007799649, 4904.7302398367, 4969.801207951641, 5026.798928042837, 5047.671901326402], "value": 4367.568695861314, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.9247941774847, 356.8472545064356, 366.4960679066284, 371.97210209234316, 374.3168717091309, 5068.239764035397, 5084.898302033962, 5185.974070129644, 5258.158694608864, 5352.0, 4666.556677115677, 5240.973795131029, 5393.0, 5410.648307859987, 5483.835484935448, 3908.0816008238185, 4270.423492828467, 4306.397104405386, 4306.5262821089655, 4346.739195648255, 3355.513450549669, 4185.539590645027, 4279.657627389802, 4285.528591854909, 4328.956710432887, 4220.459532270715, 4234.597713217239, 4237.643953934742, 4242.257604919127, 4273.850415235467, 3581.982090089559, 4186.079062606222, 4229.281022226229, 4234.259004674185, 4264.123785534355, 3766.924661506782, 3787.7273635818287, 3809.257194847006, 3809.6380843819793, 3809.714271429626, 3889.2221555688975, 4270.765107919063, 4389.648828093745, 5130.97434512825, 5369.812056578001, 4005.7796821174825, 4215.78921053946, 4937.23472861707, 4955.653104282702, 4975.129352363322, 4590.931136032962, 5427.670220795931, 5796.956547821391, 5839.620424672383, 5855.941440585582, 4098.426220329156, 4135.400366946806, 4158.792060396999, 4169.478815148106, 4192.056787222881, 5006.774695138727, 5206.813138481809, 6105.412592725879, 6110.908336374958, 6208.906866397007, 3954.7937878947196, 3980.3432433648704, 4262.488501379854, 4280.700350975402, 4292.819474644498, 3815.0, 4126.773027483518, 4297.3768803523935, 4368.7815609219415, 4374.600127959023, 4844.103840789465, 4875.756212189376, 4957.504249575014, 5017.774200161001, 5022.171341728574, 5897.908886855946, 5909.349971503131, 5913.70431478433, 6000.649853782951, 6015.3383127855905, 1101.8292164714321, 1266.5250531050856, 1273.9681507962189, 1301.0762358725221, 1322.5172811923771, 757.6325482141157, 871.5772850167664, 885.2696525366598, 886.8536691445839, 888.8533391990378, 4206.85276015338, 4578.771061446914, 5132.2534549778875, 5140.203268493315, 5193.818216362485, 5023.497650234947, 5102.188723003281, 5286.97356513215, 5659.462351076641, 5697.344805347355, 4983.551480366785, 5126.461721519261, 5142.357205349332, 5161.071007218737, 5194.0, 2588.818782685194, 2923.327634644044, 2991.536311871663, 3013.8643761030808, 3025.6217972753407, 4868.537488938545, 5222.712750798667, 5500.22996780451, 5753.612250915907, 5893.640624843883, 4590.449146102423, 4795.712257264575, 5381.434949330264, 5457.808976685797, 5890.381509941394, 5876.619968912597, 5920.407959204132, 5943.613665111754, 5968.761249550056, 5995.520358371233, 5073.340465739578, 5204.386640141568, 5665.745041473061, 5719.656820590778, 5766.4233576642, 4256.4679415073115, 4963.205887058053, 5445.83662490126, 5928.970355148369, 5951.732172052181, 5624.0, 5702.175303902712, 5819.301683797971, 6059.72731227087, 6241.751649669994, 3632.8365223565534, 3774.5659249185596, 3954.1893911747447, 3996.420519024783, 4012.813016905735, 5927.940720592911, 5939.732712027882, 5940.485176280194, 5949.0, 5977.953858074907, 5138.820141295187, 5255.237990491434, 5762.4764559822015, 5815.546113471632, 5883.588148829541, 4779.518349311725, 4978.9130738942085, 5288.338957630296, 5624.718764061862, 5769.192313076089, 4648.7443190624845, 4887.560119589112, 4981.501849814989, 5558.803103656875, 5625.521830644441, 4535.297028960516, 4668.813247470063, 5325.573954083587, 5772.94227057724, 5783.438471612725, 4711.246200607886, 4934.703917764946, 5623.578231632562, 5626.127950167731, 5650.971745141248], "value": 4153.172702272287, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.3149019490313, 694.17046629278, 743.4089898530667, 771.5949126708477, 828.3869936247177, 14078.788818167723, 15043.398264069452, 15068.342482326963, 15074.095554266753, 15074.39702411905, 13614.319284035784, 13735.725285494284, 13762.174269543835, 13779.17324960503, 13780.379882905268, 652.8890088684911, 822.1860358245339, 838.534613289624, 838.6016642094986, 839.6599377252193, 680.5508364479444, 796.0765512006074, 838.4172999765166, 854.679495189304, 857.7169534053747, 887.5784002598779, 889.9911000889973, 892.2103938014872, 893.5085702863431, 895.1987970766161, 860.2515811244195, 880.2869675562814, 882.4131952251745, 882.761654353321, 883.1698203688544, 662.9370209830058, 665.3446355339986, 666.4335314982275, 666.8432918264205, 666.9199696036458, 13852.238126903016, 13908.374123164482, 13910.304484775821, 14225.928870355685, 15023.09861408319, 13796.0, 14037.929810350883, 14111.234882240578, 14125.717485650332, 14261.0, 11522.76954460906, 11531.07751379896, 11658.94170529142, 11690.941545292304, 11913.523459061627, 3533.752637315389, 3645.446165739941, 3648.2521083177885, 3650.1787097903025, 3816.9884980480197, 106181.0, 108713.45643271891, 109141.0, 110228.0, 111621.0, 10702.732431689114, 10800.45997700112, 10813.648813975757, 11002.724931876766, 11088.168387370979, 21303.254386096723, 21665.67501487479, 21680.0, 21713.457163571038, 21756.238531651317, 7454.084990129003, 7468.468066322486, 7472.925270747223, 7535.02044734192, 7665.233476652402, 3753.512043434336, 3980.3631418972827, 4125.257453658311, 4137.834486620562, 4163.479565054368, 3366.612839523486, 3421.2815308784934, 3425.8972230833115, 3430.2624935638955, 3432.9485057724155, 562.7242651101013, 618.9319174890759, 637.1366797988659, 639.1690801957436, 650.7982525417136, 602.9035354343372, 615.1480199923138, 628.9308176100625, 657.8717150155769, 659.2418718473698, 9009.504477253682, 9566.464886805003, 10405.42770147634, 10680.252382333318, 10806.945965270123, 519.5246349590158, 551.3383939272809, 568.3605943313772, 575.2722805650855, 579.8289504596154, 4595.264757638762, 4918.483559226302, 4922.237053256751, 4935.383077115361, 4935.703857768545, 88616.5569172163, 91737.0, 95858.52070739602, 99326.5033674827, 102738.48630756947, 62796.0, 72883.54232915418, 75795.86306205411, 79491.60254198808, 84911.0, 74473.88289175667, 78475.21524784679, 80908.0, 81808.0, 82340.58829705932, 10583.629572965217, 10674.573017079541, 10764.677059688222, 10899.945500272448, 10945.781084378426, 44857.55142448534, 50286.74856625694, 50573.74713126411, 51136.48863511317, 51574.0, 60574.78850423055, 62832.37167628265, 66316.6684166576, 66646.0, 68197.0, 10234.334768240338, 11623.0, 12167.513299467922, 12395.442205100611, 12680.809787853374, 14017.929910350384, 14347.067440616327, 14516.782248266496, 14537.491187808377, 14544.927275363556, 12982.89203873603, 13359.265240411481, 13393.933030334785, 13511.662208444868, 13854.722905542034, 10381.784659661766, 12320.0, 13251.80122298147, 13390.196588204739, 13485.123466974615, 12365.258084514959, 13251.0, 13343.599692009255, 13349.065565410716, 13408.530701425794, 10660.307080039773, 10822.567097316334, 11483.712907177387, 11633.263879520024, 11703.23928944616, 10154.39073655583, 10852.837207442053, 11028.944855275673, 11056.060234880126, 11064.39145846954], "value": 7290.881532120698, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1565.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 117.0, 117.0, 116.0, 115.0, 119.0, 116.0, 117.0, 118.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 110.0, 113.0, 146.0, 114.0, 114.0, 113.0, 111.0, 131.0, 117.0], "value": 120.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 201.0, 193.0, 201.0, 198.0, 194.0, 196.0, 200.0, 200.0, 197.0], "value": 197.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 58.0, 86.0, 61.0, 62.0, 76.0, 80.0, 97.0, 83.0], "value": 74.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 86.0, 73.0, 61.0, 74.0, 104.0, 69.0, 91.0, 107.0], "value": 82.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 82.0, 92.0, 73.0, 136.0, 133.0, 130.0, 88.0, 90.0, 87.0], "value": 98.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 110.0, 91.0, 146.0, 89.0, 137.0, 156.0, 122.0, 155.0, 151.0], "value": 126.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 93.0, 74.0, 144.0, 148.0, 90.0, 92.0, 95.0, 94.0], "value": 101.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 63.0, 62.0, 64.0, 62.0, 75.0, 79.0, 64.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 78.0, 68.0, 72.0, 98.0, 56.0, 69.0, 97.0, 104.0, 102.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 107.0, 106.0, 74.0, 105.0, 109.0, 103.0, 105.0, 85.0], "value": 97.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 138.0, 157.0, 147.0, 162.0, 162.0, 154.0, 159.0, 192.0, 128.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 169.0, 173.0, 200.0, 190.0, 164.0, 172.0, 170.0, 168.0], "value": 174.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 68.0, 69.0, 84.0, 87.0, 87.0, 87.0, 90.0, 66.0], "value": 77.4, "unit": "score"}]}]} 07:12:14 INFO - Return code: 0 07:12:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:12:14 ERROR - # TBPL SUCCESS # 07:12:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:12:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:12:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:12:14 INFO - 2017-03-02 07:12:14.144 screenresolution[1659:10661] starting screenresolution argv=screenresolution get 07:12:14 INFO - 2017-03-02 07:12:14.146 screenresolution[1659:10661] Display 0: 1600x1200x32@60 07:12:14 INFO - 2017-03-02 07:12:14.152 screenresolution[1660:10664] starting screenresolution argv=screenresolution list 07:12:14 INFO - Available Modes on Display 0 07:12:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:12:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:12:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:12:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:12:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:12:14 INFO - Intel Iris: 07:12:14 INFO - Chipset Model: Intel Iris 07:12:14 INFO - Type: GPU 07:12:14 INFO - Bus: Built-In 07:12:14 INFO - VRAM (Dynamic, Max): 1536 MB 07:12:14 INFO - Vendor: Intel (0x8086) 07:12:14 INFO - Device ID: 0x0a2e 07:12:14 INFO - Revision ID: 0x0009 07:12:14 INFO - Displays: 07:12:14 INFO - Display: 07:12:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:12:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:12:14 INFO - Display Serial Number: 1600x1200 60 07:12:14 INFO - Main Display: Yes 07:12:14 INFO - Mirror: Off 07:12:14 INFO - Online: Yes 07:12:14 INFO - Rotation: Supported 07:12:14 INFO - Return code: 0 07:12:14 INFO - Running post-action listener: _resource_record_post_action 07:12:14 INFO - [mozharness: 2017-03-02 15:12:14.393643Z] Finished run-tests step (success) 07:12:14 INFO - Running post-run listener: _resource_record_post_run 07:12:14 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 37526016; Write bytes: 511574528; Read time: 415; Write time: 1950 07:12:14 INFO - TinderboxPrint: CPU usage
54.3% 07:12:14 INFO - TinderboxPrint: I/O read bytes / time
37,526,016 / 415 07:12:14 INFO - TinderboxPrint: I/O write bytes / time
511,574,528 / 1,950 07:12:14 INFO - TinderboxPrint: CPU idle
2,187.2 (45.7%) 07:12:14 INFO - TinderboxPrint: CPU system
962.8 (20.1%) 07:12:14 INFO - TinderboxPrint: CPU user
1,639.8 (34.2%) 07:12:14 INFO - TinderboxPrint: Swap in / out
244,924,416 / 0 07:12:14 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207712768; Write bytes: 200056832; Read time: 15937; Write time: 360 07:12:14 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35117568; Write bytes: 301453824; Read time: 385; Write time: 1555 07:12:14 INFO - Running post-run listener: _upload_blobber_files 07:12:14 INFO - Blob upload gear active. 07:12:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:12:14 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 07:12:14 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:12:14 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:12:15 INFO - (blobuploader) - INFO - Open directory for files ... 07:12:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:12:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:12:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:12:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:12:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:12:16 INFO - (blobuploader) - INFO - Done attempting. 07:12:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:12:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:12:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:12:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:12:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:12:17 INFO - (blobuploader) - INFO - Done attempting. 07:12:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:12:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:12:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:12:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:12:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:12:18 INFO - (blobuploader) - INFO - Done attempting. 07:12:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:12:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:12:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:12:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:12:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:12:20 INFO - (blobuploader) - INFO - Done attempting. 07:12:20 INFO - (blobuploader) - INFO - Iteration through files over. 07:12:20 INFO - Return code: 0 07:12:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:12:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:12:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7c33d0e72c4c581373d2f9b250f4da7fb507751eb805fa4023e4312522b3a4f9f8052d31c677f3eb6d89c2ef644a88b77cefbf3974875fa0244e56ed39609d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45bdf4a2d67054e4633635297d5553efbdebe89613e7dab380ed8317f720534e3633bfccf735047472d6b6fa60d820d2cb8d50c0d3734517c7af67a3d31fdb69", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4423898632b8c3fc65fd502353f87ce3303f627fe573ea9e8b9402b109393d6c1d15e7993a427b1fc440196e12862ea1d096ceddcb3b953ae01ff3073075945e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d41f7b2dc7022275aa51931969febfb0d9417231e42e61cb81175224578bc0e01ac34db9b9692c3ee63c9ef2e977567e18ef4d527714c642346f38d2104ab281"} 07:12:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:12:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:12:20 INFO - Contents: 07:12:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7c33d0e72c4c581373d2f9b250f4da7fb507751eb805fa4023e4312522b3a4f9f8052d31c677f3eb6d89c2ef644a88b77cefbf3974875fa0244e56ed39609d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45bdf4a2d67054e4633635297d5553efbdebe89613e7dab380ed8317f720534e3633bfccf735047472d6b6fa60d820d2cb8d50c0d3734517c7af67a3d31fdb69", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4423898632b8c3fc65fd502353f87ce3303f627fe573ea9e8b9402b109393d6c1d15e7993a427b1fc440196e12862ea1d096ceddcb3b953ae01ff3073075945e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d41f7b2dc7022275aa51931969febfb0d9417231e42e61cb81175224578bc0e01ac34db9b9692c3ee63c9ef2e977567e18ef4d527714c642346f38d2104ab281"} 07:12:20 INFO - Running post-run listener: copy_logs_to_upload_dir 07:12:20 INFO - Copying logs to upload dir... 07:12:20 INFO - mkdir: /builds/slave/test/build/upload/logs 07:12:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.840919 ========= master_lag: 0.53 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-03-02 07:12:20.924307) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-02 07:12:20.929015) ========= 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.HUg90xWrAf/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.ULUt2vuXjL/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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-Aurora/sha512/d7c33d0e72c4c581373d2f9b250f4da7fb507751eb805fa4023e4312522b3a4f9f8052d31c677f3eb6d89c2ef644a88b77cefbf3974875fa0244e56ed39609d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45bdf4a2d67054e4633635297d5553efbdebe89613e7dab380ed8317f720534e3633bfccf735047472d6b6fa60d820d2cb8d50c0d3734517c7af67a3d31fdb69", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4423898632b8c3fc65fd502353f87ce3303f627fe573ea9e8b9402b109393d6c1d15e7993a427b1fc440196e12862ea1d096ceddcb3b953ae01ff3073075945e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d41f7b2dc7022275aa51931969febfb0d9417231e42e61cb81175224578bc0e01ac34db9b9692c3ee63c9ef2e977567e18ef4d527714c642346f38d2104ab281"} build_url:https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010951 build_url: 'https://queue.taskcluster.net/v1/task/LedDmrfYSuu9oN52_bHxmQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7c33d0e72c4c581373d2f9b250f4da7fb507751eb805fa4023e4312522b3a4f9f8052d31c677f3eb6d89c2ef644a88b77cefbf3974875fa0244e56ed39609d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45bdf4a2d67054e4633635297d5553efbdebe89613e7dab380ed8317f720534e3633bfccf735047472d6b6fa60d820d2cb8d50c0d3734517c7af67a3d31fdb69", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4423898632b8c3fc65fd502353f87ce3303f627fe573ea9e8b9402b109393d6c1d15e7993a427b1fc440196e12862ea1d096ceddcb3b953ae01ff3073075945e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d41f7b2dc7022275aa51931969febfb0d9417231e42e61cb81175224578bc0e01ac34db9b9692c3ee63c9ef2e977567e18ef4d527714c642346f38d2104ab281"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-02 07:12:20.971365) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 07:12:20.971758) ========= 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.HUg90xWrAf/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.ULUt2vuXjL/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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.005288 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 07:12:21.020268) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-02 07:12:21.023638) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-02 07:12:21.024063) ========= ========= Total master_lag: 1.17 =========