builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0252 starttime: 1490001355.99 results: success (0) buildid: 20170320004004 builduid: 13ed6e8dc51d41aa97db319c668aa593 revision: 6a72a11138b9b41a02455a3cb32a8994a7f41934 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 02:15:55.986963) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 02:15:55.987384) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 02:15:55.987682) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 02:15:56.014411) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 02:15:56.014769) ========= 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.boadiEIQin/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.mY5p405KtE/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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-20 02:15:56-- 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% 44.0M=0s 2017-03-20 02:15:56 (44.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102880 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 02:15:56.141963) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 02:15:56.142285) ========= 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.boadiEIQin/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.mY5p405KtE/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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.026251 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 02:15:56.190982) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 02:15:56.191352) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6a72a11138b9b41a02455a3cb32a8994a7f41934 --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 6a72a11138b9b41a02455a3cb32a8994a7f41934 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.boadiEIQin/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.mY5p405KtE/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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-20 02:15:56,317 truncating revision to first 12 chars 2017-03-20 02:15:56,318 Setting DEBUG logging. 2017-03-20 02:15:56,318 attempt 1/10 2017-03-20 02:15:56,318 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6a72a11138b9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-20 02:15:57,453 unpacking tar archive at: mozilla-aurora-6a72a11138b9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.400343 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 02:15:57.615416) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 02:15:57.615801) ========= script_repo_revision: 6a72a11138b9b41a02455a3cb32a8994a7f41934 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 02:15:57.616191) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 02:15:57.616461) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 02:15:57.636459) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-20 02:15:57.636848) ========= /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.boadiEIQin/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.mY5p405KtE/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 02:15:57 INFO - MultiFileLogger online at 20170320 02:15:57 in /builds/slave/test 02:15:57 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 02:15:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:15:57 INFO - {'append_to_log': False, 02:15:57 INFO - 'base_work_dir': '/builds/slave/test', 02:15:57 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:15:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:15:57 INFO - 'branch': 'Mozilla-Aurora', 02:15:57 INFO - 'buildbot_json_path': 'buildprops.json', 02:15:57 INFO - 'config_files': ('talos/mac_config.py',), 02:15:57 INFO - 'default_actions': ('clobber', 02:15:57 INFO - 'read-buildbot-config', 02:15:57 INFO - 'download-and-extract', 02:15:57 INFO - 'populate-webroot', 02:15:57 INFO - 'create-virtualenv', 02:15:57 INFO - 'install', 02:15:57 INFO - 'run-tests'), 02:15:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:15:57 INFO - 'download_minidump_stackwalk': True, 02:15:57 INFO - 'download_symbols': 'ondemand', 02:15:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:15:57 INFO - 'tooltool.py': '/tools/tooltool.py', 02:15:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:15:57 INFO - '/tools/misc-python/virtualenv.py')}, 02:15:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:15:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:15:57 INFO - 'gecko_profile': False, 02:15:57 INFO - 'gecko_profile_interval': 0, 02:15:57 INFO - 'installer_path': 'installer.exe', 02:15:57 INFO - 'log_level': 'info', 02:15:57 INFO - 'log_name': 'talos', 02:15:57 INFO - 'log_to_console': True, 02:15:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:15:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:15:57 INFO - 'opt_config_files': (), 02:15:57 INFO - 'pip_index': False, 02:15:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:15:57 INFO - 'cmd': ('bash', 02:15:57 INFO - '-c', 02:15:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:15:57 INFO - 'enabled': True, 02:15:57 INFO - 'halt_on_failure': False, 02:15:57 INFO - 'name': 'check_screen_resolution'},), 02:15:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:15:57 INFO - 'cmd': ('bash', 02:15:57 INFO - '-c', 02:15:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:15:57 INFO - 'enabled': True, 02:15:57 INFO - 'halt_on_failure': False, 02:15:57 INFO - 'name': 'check_screen_resolution'},), 02:15:57 INFO - 'run_cmd_checks_enabled': True, 02:15:57 INFO - 'suite': 'dromaeojs-e10s', 02:15:57 INFO - 'system_bits': '32', 02:15:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:15:57 INFO - 'title': 't-yosemite-r7-0252', 02:15:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:15:57 INFO - 'use_talos_json': True, 02:15:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:15:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:15:57 INFO - 'work_dir': 'build'} 02:15:57 INFO - [mozharness: 2017-03-20 09:15:57.866207Z] Running clobber step. 02:15:57 INFO - Running pre-action listener: _resource_record_pre_action 02:15:57 INFO - Running main action method: clobber 02:15:57 INFO - rmtree: /builds/slave/test/build 02:15:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:16:00 INFO - Running post-action listener: _resource_record_post_action 02:16:00 INFO - [mozharness: 2017-03-20 09:16:00.198175Z] Finished clobber step (success) 02:16:00 INFO - [mozharness: 2017-03-20 09:16:00.198306Z] Running read-buildbot-config step. 02:16:00 INFO - Running pre-action listener: _resource_record_pre_action 02:16:00 INFO - Running main action method: read_buildbot_config 02:16:00 INFO - Using buildbot properties: 02:16:00 INFO - { 02:16:00 INFO - "project": "", 02:16:00 INFO - "product": "firefox", 02:16:00 INFO - "script_repo_revision": "production", 02:16:00 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:16:00 INFO - "repository": "", 02:16:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:16:00 INFO - "buildid": "20170320004004", 02:16:00 INFO - "stage_platform": "macosx64", 02:16:00 INFO - "basedir": "/builds/slave/test", 02:16:00 INFO - "nightly_build": "True", 02:16:00 INFO - "buildnumber": 19, 02:16:00 INFO - "slavename": "t-yosemite-r7-0252", 02:16:00 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:16:00 INFO - "platform": "yosemite_r7", 02:16:00 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:16:00 INFO - "branch": "mozilla-aurora", 02:16:00 INFO - "repo_path": "releases/mozilla-aurora", 02:16:00 INFO - "revision": "6a72a11138b9b41a02455a3cb32a8994a7f41934", 02:16:00 INFO - "pgo_build": "False", 02:16:00 INFO - "builduid": "13ed6e8dc51d41aa97db319c668aa593", 02:16:00 INFO - "slavebuilddir": "test" 02:16:00 INFO - } 02:16:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:16:00 INFO - Running post-action listener: _resource_record_post_action 02:16:00 INFO - [mozharness: 2017-03-20 09:16:00.201837Z] Finished read-buildbot-config step (success) 02:16:00 INFO - [mozharness: 2017-03-20 09:16:00.201937Z] Running download-and-extract step. 02:16:00 INFO - Running pre-action listener: _resource_record_pre_action 02:16:00 INFO - Running main action method: download_and_extract 02:16:00 INFO - mkdir: /builds/slave/test/build/tests 02:16: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')]} 02:16:00 INFO - trying https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:16:00 INFO - Downloading https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 02:16:01 INFO - Downloaded 1199 bytes. 02:16:01 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:16:01 INFO - Using the following test package requirements: 02:16:01 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:16:01 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:01 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:16:01 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:01 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:16:01 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:01 INFO - u'jsshell-mac.zip'], 02:16:01 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:01 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:16:01 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:16:01 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:01 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:16:01 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:01 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:16:01 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:01 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:16:01 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:01 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:16:01 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:16:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:16:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:16:01 INFO - Fetch https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:16:02 INFO - Expected file size: 51782422 02:16:02 INFO - Obtained file size: 51782422 02:16:18 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:16:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:16:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:16:18 INFO - Fetch https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:16:20 INFO - Expected file size: 51782422 02:16:20 INFO - Obtained file size: 51782422 02:16:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:16:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:16:35 INFO - Fetch https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:16:36 INFO - Expected file size: 14404424 02:16:36 INFO - Obtained file size: 14404424 02:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:36 INFO - trying https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:16:36 INFO - Downloading https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:16:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 02:16:39 INFO - Downloaded 74650818 bytes. 02:16:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:16:39 INFO - mkdir: /builds/slave/test/properties 02:16:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:16:39 INFO - Writing to file /builds/slave/test/properties/build_url 02:16:39 INFO - Contents: 02:16:39 INFO - build_url:https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:16:39 INFO - Running post-action listener: _resource_record_post_action 02:16:39 INFO - Running post-action listener: set_extra_try_arguments 02:16:39 INFO - [mozharness: 2017-03-20 09:16:39.724241Z] Finished download-and-extract step (success) 02:16:39 INFO - [mozharness: 2017-03-20 09:16:39.724338Z] Running populate-webroot step. 02:16:39 INFO - Running pre-action listener: _resource_record_pre_action 02:16:39 INFO - Running main action method: populate_webroot 02:16:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:16:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:16:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:16:39 INFO - '--tppagecycles', 02:16:39 INFO - '7'], 02:16:39 INFO - 'tests': ['tsvgm']}}, 02:16:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:16:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:16:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:16:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:16:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:16:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:16:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:16:39 INFO - 'talos_options': ['--disable-e10s'], 02:16:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:16:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:16:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:16:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:16:39 INFO - 'talos_options': ['--disable-e10s'], 02:16:39 INFO - 'tests': ['damp', 'tps']}, 02:16:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:16:39 INFO - 'tests': ['damp', 'tps']}, 02:16:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:16:39 INFO - 'tests': ['dromaeo_dom']}, 02:16:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:16:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:16:39 INFO - 'tests': ['basic_compositor_video']}, 02:16:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:16:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:16:39 INFO - 'tests': ['a11yr', 02:16:39 INFO - 'ts_paint', 02:16:39 INFO - 'tpaint', 02:16:39 INFO - 'sessionrestore', 02:16:39 INFO - 'sessionrestore_no_auto_restore', 02:16:39 INFO - 'tabpaint']}, 02:16:39 INFO - 'other-e10s': {'tests': ['a11yr', 02:16:39 INFO - 'ts_paint', 02:16:39 INFO - 'tpaint', 02:16:39 INFO - 'sessionrestore', 02:16:39 INFO - 'sessionrestore_no_auto_restore', 02:16:39 INFO - 'tabpaint']}, 02:16:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:16:39 INFO - 'ts_paint', 02:16:39 INFO - 'tpaint', 02:16:39 INFO - 'sessionrestore', 02:16:39 INFO - 'sessionrestore_no_auto_restore', 02:16:39 INFO - 'tabpaint']}, 02:16:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:16:39 INFO - 'ts_paint', 02:16:39 INFO - 'tpaint', 02:16:39 INFO - 'sessionrestore', 02:16:39 INFO - 'sessionrestore_no_auto_restore', 02:16:39 INFO - 'tabpaint']}, 02:16:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:16:39 INFO - 'tests': ['a11yr', 02:16:39 INFO - 'ts_paint', 02:16:39 INFO - 'tpaint', 02:16:39 INFO - 'sessionrestore', 02:16:39 INFO - 'sessionrestore_no_auto_restore', 02:16:39 INFO - 'tabpaint']}, 02:16:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:16:39 INFO - 'tests': ['a11yr', 02:16:39 INFO - 'ts_paint', 02:16:39 INFO - 'tpaint', 02:16:39 INFO - 'sessionrestore', 02:16:39 INFO - 'sessionrestore_no_auto_restore', 02:16:39 INFO - 'tabpaint']}, 02:16:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:16:39 INFO - 'tests': ['tsvgx', 02:16:39 INFO - 'tsvgr_opacity', 02:16:39 INFO - 'tart', 02:16:39 INFO - 'tscrollx', 02:16:39 INFO - 'cart', 02:16:39 INFO - 'tsvg_static']}, 02:16:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:16:39 INFO - 'tsvgr_opacity', 02:16:39 INFO - 'tart', 02:16:39 INFO - 'tscrollx', 02:16:39 INFO - 'cart', 02:16:39 INFO - 'tsvg_static']}, 02:16:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:16:39 INFO - 'talos_options': ['--disable-e10s'], 02:16:39 INFO - 'tests': ['tp5o']}, 02:16:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:16:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:16:39 INFO - 'talos_options': ['--disable-e10s', 02:16:39 INFO - '--xperf_path', 02:16:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:16:39 INFO - 'tests': ['tp5n']}, 02:16:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:16:39 INFO - 'talos_options': ['--xperf_path', 02:16:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:16:39 INFO - 'tests': ['tp5n']}}, 02:16:39 INFO - 'talos.zip': {'path': '', 02:16:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:16:39 INFO - Running post-action listener: _resource_record_post_action 02:16:39 INFO - [mozharness: 2017-03-20 09:16:39.743037Z] Finished populate-webroot step (success) 02:16:39 INFO - [mozharness: 2017-03-20 09:16:39.743168Z] Running create-virtualenv step. 02:16:39 INFO - Running pre-action listener: _resource_record_pre_action 02:16:39 INFO - Running main action method: create_virtualenv 02:16:39 INFO - Creating virtualenv /builds/slave/test/build/venv 02:16:39 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 02:16:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:16:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:16:39 INFO - Using real prefix '/tools/python27' 02:16:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:16:40 INFO - Installing distribute.............................................................................................................................................................................................done. 02:16:43 INFO - Installing pip.................done. 02:16:43 INFO - Return code: 0 02:16:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:16:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:16:43 INFO - Reading from file tmpfile_stdout 02:16:43 INFO - Output received: 02:16:43 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) 02:16:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:43 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 0x10e14db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d0c6c6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df2bd30>, '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 0x10de52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df20be8>, '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.boadiEIQin/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.mY5p405KtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 02:16:43 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 02:16:43 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 02:16:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.boadiEIQin/Render', 02:16:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:43 INFO - 'HOME': '/Users/cltbld', 02:16:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:43 INFO - 'LOGNAME': 'cltbld', 02:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:43 INFO - 'MOZ_NO_REMOTE': '1', 02:16:43 INFO - 'NO_EM_RESTART': '1', 02:16:43 INFO - 'PAGER': '/bin/cat', 02:16:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:43 INFO - 'PWD': '/builds/slave/test', 02:16:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:43 INFO - 'SHELL': '/bin/bash', 02:16:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mY5p405KtE/Listeners', 02:16:43 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 02:16:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:43 INFO - 'USER': 'cltbld', 02:16:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:43 INFO - 'XPC_FLAGS': '0x0', 02:16:43 INFO - 'XPC_SERVICE_NAME': '0', 02:16:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:43 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 02:16:43 INFO - Cleaning up... 02:16:43 INFO - Return code: 0 02:16:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:43 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 0x10e14db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d0c6c6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df2bd30>, '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 0x10de52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df20be8>, '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.boadiEIQin/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.mY5p405KtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 02:16:43 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 02:16:43 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 02:16:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.boadiEIQin/Render', 02:16:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:43 INFO - 'HOME': '/Users/cltbld', 02:16:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:43 INFO - 'LOGNAME': 'cltbld', 02:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:43 INFO - 'MOZ_NO_REMOTE': '1', 02:16:43 INFO - 'NO_EM_RESTART': '1', 02:16:43 INFO - 'PAGER': '/bin/cat', 02:16:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:43 INFO - 'PWD': '/builds/slave/test', 02:16:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:43 INFO - 'SHELL': '/bin/bash', 02:16:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mY5p405KtE/Listeners', 02:16:43 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 02:16:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:43 INFO - 'USER': 'cltbld', 02:16:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:43 INFO - 'XPC_FLAGS': '0x0', 02:16:43 INFO - 'XPC_SERVICE_NAME': '0', 02:16:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:44 INFO - Downloading/unpacking psutil>=3.1.1 02:16:44 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 02:16:44 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 02:16:44 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 02:16:44 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 02:16:44 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 02:16:44 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 02:16:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:16:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:16:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:16:47 INFO - Installing collected packages: psutil 02:16:47 INFO - Running setup.py install for psutil 02:16:47 INFO - building 'psutil._psutil_osx' extension 02:16:47 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 02:16:48 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 02:16:48 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 02:16:48 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 02:16:48 INFO - building 'psutil._psutil_posix' extension 02:16:48 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 02:16:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:16:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:16:48 INFO - ^ 02:16:48 INFO - 1 warning generated. 02:16:48 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 02:16:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:16:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:16:48 INFO - Successfully installed psutil 02:16:48 INFO - Cleaning up... 02:16:48 INFO - Return code: 0 02:16:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:16: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')]} 02:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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')]} 02:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e14db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d0c6c6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df2bd30>, '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 0x10de52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df20be8>, '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.boadiEIQin/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.mY5p405KtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 02:16: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:16: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 mozsystemmonitor==0.3 02:16:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.boadiEIQin/Render', 02:16:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:48 INFO - 'HOME': '/Users/cltbld', 02:16:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:48 INFO - 'LOGNAME': 'cltbld', 02:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:48 INFO - 'MOZ_NO_REMOTE': '1', 02:16:48 INFO - 'NO_EM_RESTART': '1', 02:16:48 INFO - 'PAGER': '/bin/cat', 02:16:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:48 INFO - 'PWD': '/builds/slave/test', 02:16:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:48 INFO - 'SHELL': '/bin/bash', 02:16:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mY5p405KtE/Listeners', 02:16:48 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 02:16:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:48 INFO - 'USER': 'cltbld', 02:16:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:48 INFO - 'XPC_FLAGS': '0x0', 02:16:48 INFO - 'XPC_SERVICE_NAME': '0', 02:16:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:16:49 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 02:16:49 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 02:16:49 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 02:16:49 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 02:16:49 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 02:16:49 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 02:16:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:16:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:16:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:16:51 INFO - Installing collected packages: mozsystemmonitor 02:16:51 INFO - Running setup.py install for mozsystemmonitor 02:16:52 INFO - Successfully installed mozsystemmonitor 02:16:52 INFO - Cleaning up... 02:16:52 INFO - Return code: 0 02:16:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e14db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d0c6c6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df2bd30>, '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 0x10de52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df20be8>, '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.boadiEIQin/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.mY5p405KtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 02:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:16:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.boadiEIQin/Render', 02:16:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:52 INFO - 'HOME': '/Users/cltbld', 02:16:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:52 INFO - 'LOGNAME': 'cltbld', 02:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:52 INFO - 'MOZ_NO_REMOTE': '1', 02:16:52 INFO - 'NO_EM_RESTART': '1', 02:16:52 INFO - 'PAGER': '/bin/cat', 02:16:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:52 INFO - 'PWD': '/builds/slave/test', 02:16:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:52 INFO - 'SHELL': '/bin/bash', 02:16:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mY5p405KtE/Listeners', 02:16:52 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 02:16:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:52 INFO - 'USER': 'cltbld', 02:16:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:52 INFO - 'XPC_FLAGS': '0x0', 02:16:52 INFO - 'XPC_SERVICE_NAME': '0', 02:16:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:53 INFO - Downloading/unpacking jsonschema==2.5.1 02:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:16:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:16:56 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 02:16:56 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 02:16:56 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 02:16:56 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 02:16:56 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 02:16:56 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 02:16:56 INFO - Downloading functools32-3.2.3-2.tar.gz 02:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:16:56 INFO - warning: no files found matching '*.txt' 02:16:56 INFO - no previously-included directories found matching 'build' 02:16:56 INFO - no previously-included directories found matching 'dist' 02:16:56 INFO - no previously-included directories found matching '.git*' 02:16:56 INFO - Installing collected packages: jsonschema, functools32 02:16:56 INFO - Running setup.py install for functools32 02:16:56 INFO - warning: no files found matching '*.txt' 02:16:56 INFO - no previously-included directories found matching 'build' 02:16:56 INFO - no previously-included directories found matching 'dist' 02:16:56 INFO - no previously-included directories found matching '.git*' 02:16:56 INFO - Successfully installed jsonschema functools32 02:16:56 INFO - Cleaning up... 02:16:56 INFO - Return code: 0 02:16:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:16:56 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')]} 02:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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')]} 02:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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', '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 0x10e14db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d0c6c6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df2bd30>, '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 0x10de52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df20be8>, '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.boadiEIQin/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.mY5p405KtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 02:16: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:16: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 functools32==3.2.3-2 02:16:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.boadiEIQin/Render', 02:16:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:57 INFO - 'HOME': '/Users/cltbld', 02:16:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:57 INFO - 'LOGNAME': 'cltbld', 02:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:57 INFO - 'MOZ_NO_REMOTE': '1', 02:16:57 INFO - 'NO_EM_RESTART': '1', 02:16:57 INFO - 'PAGER': '/bin/cat', 02:16:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:57 INFO - 'PWD': '/builds/slave/test', 02:16:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:57 INFO - 'SHELL': '/bin/bash', 02:16:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mY5p405KtE/Listeners', 02:16:57 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 02:16:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:57 INFO - 'USER': 'cltbld', 02:16:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:57 INFO - 'XPC_FLAGS': '0x0', 02:16:57 INFO - 'XPC_SERVICE_NAME': '0', 02:16:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:16:57 INFO - Cleaning up... 02:16:57 INFO - Return code: 0 02:16:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:16: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')]} 02:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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')]} 02:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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', '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 0x10e14db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d0c6c6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df2bd30>, '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 0x10de52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df20be8>, '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.boadiEIQin/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.mY5p405KtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 02:16: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:16: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 blobuploader==1.2.4 02:16:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.boadiEIQin/Render', 02:16:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:57 INFO - 'HOME': '/Users/cltbld', 02:16:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:57 INFO - 'LOGNAME': 'cltbld', 02:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:57 INFO - 'MOZ_NO_REMOTE': '1', 02:16:57 INFO - 'NO_EM_RESTART': '1', 02:16:57 INFO - 'PAGER': '/bin/cat', 02:16:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:57 INFO - 'PWD': '/builds/slave/test', 02:16:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:57 INFO - 'SHELL': '/bin/bash', 02:16:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mY5p405KtE/Listeners', 02:16:57 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 02:16:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:57 INFO - 'USER': 'cltbld', 02:16:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:57 INFO - 'XPC_FLAGS': '0x0', 02:16:57 INFO - 'XPC_SERVICE_NAME': '0', 02:16:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:57 INFO - Downloading/unpacking blobuploader==1.2.4 02:16: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 02:16: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 02:16: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 02:16: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 02:16: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 02:16: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 02:17:00 INFO - Downloading blobuploader-1.2.4.tar.gz 02:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:17:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:17:00 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 02:17:00 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 02:17:00 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 02:17:00 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 02:17:00 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 02:17:00 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 02:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:17:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:17: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 02:17: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 02:17: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 02:17: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 02:17: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 02:17: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 02:17:01 INFO - Downloading docopt-0.6.1.tar.gz 02:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:17:01 INFO - Installing collected packages: blobuploader, requests, docopt 02:17:01 INFO - Running setup.py install for blobuploader 02:17:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:17:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:17:01 INFO - Running setup.py install for requests 02:17:02 INFO - Running setup.py install for docopt 02:17:02 INFO - Successfully installed blobuploader requests docopt 02:17:02 INFO - Cleaning up... 02:17:02 INFO - Return code: 0 02:17:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:17:02 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')]} 02:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:02 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')]} 02:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:02 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 0x10e14db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d0c6c6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df2bd30>, '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 0x10de52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df20be8>, '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.boadiEIQin/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.mY5p405KtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 02:17:02 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 02:17:02 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 02:17:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.boadiEIQin/Render', 02:17:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:02 INFO - 'HOME': '/Users/cltbld', 02:17:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:02 INFO - 'LOGNAME': 'cltbld', 02:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:02 INFO - 'MOZ_NO_REMOTE': '1', 02:17:02 INFO - 'NO_EM_RESTART': '1', 02:17:02 INFO - 'PAGER': '/bin/cat', 02:17:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:02 INFO - 'PWD': '/builds/slave/test', 02:17:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:02 INFO - 'SHELL': '/bin/bash', 02:17:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mY5p405KtE/Listeners', 02:17:02 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 02:17:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:02 INFO - 'USER': 'cltbld', 02:17:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:02 INFO - 'XPC_FLAGS': '0x0', 02:17:02 INFO - 'XPC_SERVICE_NAME': '0', 02:17:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:17:02 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-jGU4uC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:17:03 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-VAfL0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:17:03 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-GgDPgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:17:03 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-AmZZAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:17:03 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-cOAPvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:17:03 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-Eo6HYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:17:03 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-gWXKKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:17:03 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-UOAmFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:17:03 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-YY5_MK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:17:03 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-LCMQcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:17:04 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-Xx1ZKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:17:04 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-ONEFSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:17:04 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-Sajx8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:17:04 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-A2EZMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:17:04 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-KCig4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:17:04 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-shGEVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:17:04 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-s6ISsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:17:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:17:04 INFO - Running setup.py install for manifestparser 02:17:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:17:04 INFO - Running setup.py install for mozcrash 02:17:05 INFO - Running setup.py install for mozdebug 02:17:05 INFO - Running setup.py install for mozdevice 02:17:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:17:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:17:05 INFO - Running setup.py install for mozfile 02:17:05 INFO - Running setup.py install for mozhttpd 02:17:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:17:05 INFO - Running setup.py install for mozinfo 02:17:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:17:05 INFO - Running setup.py install for mozInstall 02:17:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:17:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:17:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:17:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:17:05 INFO - Running setup.py install for mozleak 02:17:06 INFO - Running setup.py install for mozlog 02:17:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:17:06 INFO - Running setup.py install for moznetwork 02:17:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:17:06 INFO - Running setup.py install for mozprocess 02:17:06 INFO - Running setup.py install for mozprofile 02:17:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:17:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:17:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:17:06 INFO - Running setup.py install for mozrunner 02:17:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:17:06 INFO - Running setup.py install for mozscreenshot 02:17:07 INFO - Running setup.py install for moztest 02:17:07 INFO - Running setup.py install for mozversion 02:17:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:17:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:17:07 INFO - Cleaning up... 02:17:07 INFO - Return code: 0 02:17:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:07 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 0x10e14db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d0c6c6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df2bd30>, '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 0x10de52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df20be8>, '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.boadiEIQin/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.mY5p405KtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 02:17:07 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 02:17:07 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 02:17:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.boadiEIQin/Render', 02:17:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:07 INFO - 'HOME': '/Users/cltbld', 02:17:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:07 INFO - 'LOGNAME': 'cltbld', 02:17:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:07 INFO - 'MOZ_NO_REMOTE': '1', 02:17:07 INFO - 'NO_EM_RESTART': '1', 02:17:07 INFO - 'PAGER': '/bin/cat', 02:17:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:07 INFO - 'PWD': '/builds/slave/test', 02:17:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:07 INFO - 'SHELL': '/bin/bash', 02:17:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mY5p405KtE/Listeners', 02:17:07 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 02:17:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:07 INFO - 'USER': 'cltbld', 02:17:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:07 INFO - 'XPC_FLAGS': '0x0', 02:17:07 INFO - 'XPC_SERVICE_NAME': '0', 02:17:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:17:07 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-wAvmqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:17:07 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)) 02:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:17:07 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-pq2bJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:17:07 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)) 02:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:17:07 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-PE7_Ok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:17:07 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)) 02:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:17:07 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-wsLjtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:17:08 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)) 02:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:17:08 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-wP_sPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:17:08 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)) 02:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:17:08 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-W_ML6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:17:08 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)) 02:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:17:08 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-UJjhC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:17:08 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)) 02:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:17:08 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-8FFSk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:17:08 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)) 02:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:17:08 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-pljayj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:17:08 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)) 02:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:17:08 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-IIJwdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:17:08 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)) 02:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:17:08 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-deYMd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:17:08 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)) 02:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:17:08 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-FRCY8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:17:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 02:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:17:08 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-Gn4n74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:17:08 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)) 02:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:17:08 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-el768M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:17:09 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)) 02:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:17:09 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-u1Is8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:17:09 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)) 02:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:17:09 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-_jO6oa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:17:09 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)) 02:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:17:09 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-GoxXxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:17:09 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)) 02:17:09 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)) 02:17:09 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)) 02:17:09 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)) 02:17:09 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)) 02:17:09 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)) 02:17:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:17:09 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 02:17:09 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 02:17:09 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 02:17:09 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 02:17:09 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 02:17:09 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 02:17:12 INFO - Downloading blessings-1.6.tar.gz 02:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:17:12 INFO - Installing collected packages: blessings 02:17:12 INFO - Running setup.py install for blessings 02:17:12 INFO - Successfully installed blessings 02:17:12 INFO - Cleaning up... 02:17:12 INFO - Return code: 0 02:17:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:17:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:17:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:17:12 INFO - Reading from file tmpfile_stdout 02:17:12 INFO - Current package versions: 02:17:12 INFO - blessings == 1.6 02:17:12 INFO - blobuploader == 1.2.4 02:17:12 INFO - docopt == 0.6.1 02:17:12 INFO - functools32 == 3.2.3-2 02:17:12 INFO - jsonschema == 2.5.1 02:17:12 INFO - manifestparser == 1.1 02:17:12 INFO - mozInstall == 1.12 02:17:12 INFO - mozcrash == 1.0 02:17:12 INFO - mozdebug == 0.1 02:17:12 INFO - mozdevice == 0.49 02:17:12 INFO - mozfile == 1.2 02:17:12 INFO - mozhttpd == 0.7 02:17:12 INFO - mozinfo == 0.9 02:17:12 INFO - mozleak == 0.1 02:17:12 INFO - mozlog == 3.4 02:17:12 INFO - moznetwork == 0.27 02:17:12 INFO - mozprocess == 0.25 02:17:12 INFO - mozprofile == 0.28 02:17:12 INFO - mozrunner == 6.13 02:17:12 INFO - mozscreenshot == 0.1 02:17:12 INFO - mozsystemmonitor == 0.3 02:17:12 INFO - moztest == 0.8 02:17:12 INFO - mozversion == 1.4 02:17:12 INFO - psutil == 3.1.1 02:17:12 INFO - requests == 1.2.3 02:17:12 INFO - wsgiref == 0.1.2 02:17:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:12 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 0x10e14db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d0c6c6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df2bd30>, '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 0x10de52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df20be8>, '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.boadiEIQin/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.mY5p405KtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 02:17:12 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 02:17:12 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 02:17:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.boadiEIQin/Render', 02:17:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:12 INFO - 'HOME': '/Users/cltbld', 02:17:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:12 INFO - 'LOGNAME': 'cltbld', 02:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:12 INFO - 'MOZ_NO_REMOTE': '1', 02:17:12 INFO - 'NO_EM_RESTART': '1', 02:17:12 INFO - 'PAGER': '/bin/cat', 02:17:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:12 INFO - 'PWD': '/builds/slave/test', 02:17:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:12 INFO - 'SHELL': '/bin/bash', 02:17:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mY5p405KtE/Listeners', 02:17:12 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 02:17:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:12 INFO - 'USER': 'cltbld', 02:17:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:12 INFO - 'XPC_FLAGS': '0x0', 02:17:12 INFO - 'XPC_SERVICE_NAME': '0', 02:17:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:12 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)) 02:17:12 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)) 02:17:12 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)) 02:17:12 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)) 02:17:12 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)) 02:17:12 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)) 02:17:12 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)) 02:17:12 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)) 02:17:12 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)) 02:17:12 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)) 02:17:12 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)) 02:17:12 INFO - Cleaning up... 02:17:12 INFO - Return code: 0 02:17:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e14db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d0c6c6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df2bd30>, '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 0x10de52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df20be8>, '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.boadiEIQin/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.mY5p405KtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 02:17:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:17:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:17:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.boadiEIQin/Render', 02:17:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:12 INFO - 'HOME': '/Users/cltbld', 02:17:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:12 INFO - 'LOGNAME': 'cltbld', 02:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:12 INFO - 'MOZ_NO_REMOTE': '1', 02:17:12 INFO - 'NO_EM_RESTART': '1', 02:17:12 INFO - 'PAGER': '/bin/cat', 02:17:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:12 INFO - 'PWD': '/builds/slave/test', 02:17:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:12 INFO - 'SHELL': '/bin/bash', 02:17:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mY5p405KtE/Listeners', 02:17:12 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 02:17:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:12 INFO - 'USER': 'cltbld', 02:17:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:12 INFO - 'XPC_FLAGS': '0x0', 02:17:12 INFO - 'XPC_SERVICE_NAME': '0', 02:17:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:17:13 INFO - Cleaning up... 02:17:13 INFO - Return code: 0 02:17:13 INFO - Running post-action listener: _resource_record_post_action 02:17:13 INFO - Running post-action listener: _start_resource_monitoring 02:17:13 INFO - Starting resource monitoring. 02:17:13 INFO - [mozharness: 2017-03-20 09:17:13.236298Z] Finished create-virtualenv step (success) 02:17:13 INFO - [mozharness: 2017-03-20 09:17:13.237221Z] Running install step. 02:17:13 INFO - Running pre-action listener: _resource_record_pre_action 02:17:13 INFO - Running main action method: install 02:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:17:13 INFO - Reading from file tmpfile_stdout 02:17:13 INFO - Detecting whether we're running mozinstall >=1.0... 02:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:17:13 INFO - Reading from file tmpfile_stdout 02:17:13 INFO - Output received: 02:17:13 INFO - Usage: mozinstall [options] installer 02:17:13 INFO - Options: 02:17:13 INFO - -h, --help show this help message and exit 02:17:13 INFO - -d DEST, --destination=DEST 02:17:13 INFO - Directory to install application into. [default: 02:17:13 INFO - "/builds/slave/test"] 02:17:13 INFO - --app=APP Application being installed. [default: firefox] 02:17:13 INFO - mkdir: /builds/slave/test/build/application 02:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:17:33 INFO - Reading from file tmpfile_stdout 02:17:33 INFO - Output received: 02:17:33 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:17:33 INFO - Running post-action listener: _resource_record_post_action 02:17:33 INFO - [mozharness: 2017-03-20 09:17:33.889263Z] Finished install step (success) 02:17:33 INFO - [mozharness: 2017-03-20 09:17:33.889405Z] Running run-tests step. 02:17:33 INFO - Running pre-action listener: _resource_record_pre_action 02:17:33 INFO - Running main action method: run_tests 02:17:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:17:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:17:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:17:33 INFO - 2017-03-20 02:17:33.903 screenresolution[1624:7339] starting screenresolution argv=screenresolution get 02:17:33 INFO - 2017-03-20 02:17:33.906 screenresolution[1624:7339] Display 0: 1600x1200x32@60 02:17:33 INFO - 2017-03-20 02:17:33.912 screenresolution[1625:7342] starting screenresolution argv=screenresolution list 02:17:33 INFO - Available Modes on Display 0 02:17:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:17:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:17:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:17:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:17:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:17:34 INFO - Intel Iris: 02:17:34 INFO - Chipset Model: Intel Iris 02:17:34 INFO - Type: GPU 02:17:34 INFO - Bus: Built-In 02:17:34 INFO - VRAM (Dynamic, Max): 1536 MB 02:17:34 INFO - Vendor: Intel (0x8086) 02:17:34 INFO - Device ID: 0x0a2e 02:17:34 INFO - Revision ID: 0x0009 02:17:34 INFO - Displays: 02:17:34 INFO - Display: 02:17:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:17:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:17:34 INFO - Display Serial Number: 1600x1200 60 02:17:34 INFO - Main Display: Yes 02:17:34 INFO - Mirror: Off 02:17:34 INFO - Online: Yes 02:17:34 INFO - Rotation: Supported 02:17:34 INFO - Return code: 0 02:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:17:34 INFO - Python 2.7.3 02:17:34 INFO - Return code: 0 02:17:34 INFO - grabbing minidump binary from tooltool 02:17:34 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')]} 02:17:34 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 0x10df2bd30>, '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 0x10de52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df20be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:17:34 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 02:17:34 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 02:17:34 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 02:17:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:17:34 INFO - Return code: 0 02:17:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:17:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:17:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:17:34 INFO - ENV: RUST_BACKTRACE is now 1 02:17:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:17:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:17:34 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/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0252', '--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 02:17:34 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/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0252 --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 02:17:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.boadiEIQin/Render', 02:17:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:34 INFO - 'HOME': '/Users/cltbld', 02:17:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:34 INFO - 'LOGNAME': 'cltbld', 02:17:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:17:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:34 INFO - 'MOZ_NO_REMOTE': '1', 02:17:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:17:34 INFO - 'NO_EM_RESTART': '1', 02:17:34 INFO - 'PAGER': '/bin/cat', 02:17:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:34 INFO - 'PWD': '/builds/slave/test', 02:17:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:17:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:34 INFO - 'RUST_BACKTRACE': '1', 02:17:34 INFO - 'SHELL': '/bin/bash', 02:17:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mY5p405KtE/Listeners', 02:17:34 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 02:17:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:34 INFO - 'USER': 'cltbld', 02:17:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:34 INFO - 'XPC_FLAGS': '0x0', 02:17:34 INFO - 'XPC_SERVICE_NAME': '0', 02:17:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:34 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/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0252', '--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 02:17:34 INFO - mozversion application_buildid: 20170320004004 02:17:34 INFO - mozversion application_changeset: 6a72a11138b9b41a02455a3cb32a8994a7f41934 02:17:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:17:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:17:34 INFO - mozversion application_name: Firefox 02:17:34 INFO - mozversion application_remotingname: firefox-dev 02:17:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:17:34 INFO - mozversion application_vendor: Mozilla 02:17:34 INFO - mozversion application_version: 54.0a2 02:17:34 INFO - mozversion platform_buildid: 20170320004004 02:17:34 INFO - mozversion platform_changeset: 6a72a11138b9b41a02455a3cb32a8994a7f41934 02:17:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:17:34 INFO - mozversion platform_version: 54.0a2 02:17:34 INFO - using testdate: 1490001454 02:17:34 INFO - actual date: 1490001454 02:17:34 INFO - starting webserver on 'localhost:49238' 02:17:34 INFO - SUITE-START | Running 2 tests 02:17:34 INFO - TEST-START | dromaeo_css 02:17:34 INFO - operating with platform_type : mac_ 02:17:34 INFO - Initialising browser for dromaeo_css test... 02:17:34 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmphNfmGe/profile http://localhost:49238/getInfo.html) 02:17:37 INFO - PROCESS | 1635 | 2017-03-20 02:17:37.813 plugin-container[1638:7520] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f43, name = 'com.apple.tsm.portname' 02:17:37 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:17:37 INFO - PROCESS | 1635 | 2017-03-20 02:17:37.814 plugin-container[1638:7520] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d07, name = 'com.apple.CFPasteboardClient' 02:17:37 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:17:37 INFO - PROCESS | 1635 | 2017-03-20 02:17:37.814 plugin-container[1638:7520] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:17:37 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 02:17:37 INFO - PROCESS | 1635 | colorDepth:24 02:17:37 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 02:17:37 INFO - PROCESS | 1635 | __metrics 02:17:37 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 02:17:38 INFO - TEST-INFO | 1635: exit 0 02:17:38 INFO - Browser initialized. 02:17:38 INFO - Running cycle 1/1 for dromaeo_css test... 02:17:38 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmphNfmGe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:17:41 INFO - PROCESS | 1639 | 2017-03-20 02:17:41.151 plugin-container[1640:7657] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 02:17:41 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:17:41 INFO - PROCESS | 1639 | 2017-03-20 02:17:41.154 plugin-container[1640:7657] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 02:17:41 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:17:41 INFO - PROCESS | 1639 | 2017-03-20 02:17:41.154 plugin-container[1640:7657] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:17:50 INFO - PROCESS | 1639 | RSS: Main: 220381184 02:17:50 INFO - PROCESS | 1639 | 02:20:54 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49238/tests/dromaeo/cssquery-ext.html) 02:20:56 INFO - PROCESS | 1639 | RSS: Main: 238264320 02:20:56 INFO - PROCESS | 1639 | 02:22:39 INFO - PROCESS | 1639 | ************************* 02:22:39 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 02:22:39 INFO - PROCESS | 1639 | 02:22:39 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:22:39 INFO - PROCESS | 1639 | Full stack: 02:22:39 INFO - PROCESS | 1639 | ************************* 02:23:58 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-ext.html (next: http://localhost:49238/tests/dromaeo/cssquery-jquery.html) 02:23:59 INFO - PROCESS | 1639 | RSS: Main: 247480320 02:23:59 INFO - PROCESS | 1639 | 02:27:01 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49238/tests/dromaeo/cssquery-mootools.html) 02:27:01 INFO - PROCESS | 1639 | RSS: Main: 255508480 02:27:01 INFO - PROCESS | 1639 | 02:30:03 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49238/tests/dromaeo/cssquery-prototype.html) 02:30:04 INFO - PROCESS | 1639 | RSS: Main: 252026880 02:30:04 INFO - PROCESS | 1639 | 02:33:07 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49238/tests/dromaeo/cssquery-yui.html) 02:33:08 INFO - PROCESS | 1639 | RSS: Main: 257138688 02:33:08 INFO - PROCESS | 1639 | 02:36:10 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-yui.html (next: http://localhost:49238/tests/dromaeo/cssquery-dojo.html) 02:36:11 INFO - PROCESS | 1639 | RSS: Main: 254337024 02:36:11 INFO - PROCESS | 1639 | 02:36:11 INFO - PROCESS | 1639 | __start_tp_report 02:36:11 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 02:36:11 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 02:36:11 INFO - PROCESS | 1639 | |i|pagename|runs| 02:36:11 INFO - PROCESS | 1639 | |0;dojo.html;2815.4369126174765;3495.1961048958724;3499.4051011328056;3502.5796904371477;3552.431610942248;4875.341828853104;6041.818745437638;6077.6049556778835;6106.297775755789;6108.877822443548;6053.969730151354;6119.112728654342;6124.142620033199;6147.661878596676;6163.537734669906;5417.241586177938;5470.91793623094;5497.092979658351;5519.724013799314;5602.579806514527;5544.584156188282;5545.916811247815;5563.471470210324;5565.165225216229;5571.192177134313;4348.478182618091;4791.928121078186;5312.681239125645;5522.392536820947;5568.136938774496;4535.455745310567;5409.5672346212405;5442.945570544283;5501.229827824068;5527.198556209327;5366.651167674088;5473.67157970519;5474.589405794582;5487.478689524529;5502.697351645658;5810.302763668345;6070.908936365914;6159.291681456645;6215.192025036717;6236.812895613093;6308.905366419507;6310.242770867525;6316.62100273985;6372.490200783926;6384.457321127709;6588.835279118012;6610.239822420435;6615.305392933721;6629.138212032453;6630.900536491957;4510.571495707903;5983.833152535258;6192;6228.34602366754;6266.091416744546;6349.238091428966;6385.169927909342;6596.208454985433;6695.866082678319;6725.730970761213;5292.735363231823;6390.233172019387;6424.357564243632;6438.935610643927;6442.871142577123;6533.738650453929;6564.573302735403;6572.934270657328;6579.934200657932;6596.967015164893;6312.147860038928;6323.051542268605;6362.745490180433;6444.420002199825;6514.413702766774;6268.968655156787;6465.3857883501;6508.511861610398;6519.902201466982;6531.934680653228;4982.153033984231;5103.8468845934685;5209.609279304067;5216.634835561475;5222.947770522323;4624.075184963021;4641;4641.094986477605;4668.766561671903;4675.275332323496;5997.730102145327;6070.453659170696;6191.380861913773;6293.402126797947;6331.461825744771;4738.19450693383;6232.127502149703;6352.2694890087305;6382.617042977344;6518.934810651927;6236.438720515175;6238.563300569007;6262.53031022666;6263;6274.6548939808745;5745.540356771532;5752.654840709571;5754.424557544212;5777.768889244383;5799.3620701722775;6335.524835637253;6512.381323774234;6647.7008534617025;6797.819529817372;6802.897956530656;6530.379613936669;6560.770373036921;6591.978243372285;6592.70332835014;6611.504137189634;6757.763478278237;6768.932310676831;6775.525713200029;6869.244383117753;6959.990801333879;5391.838244852661;6236;6288.748450061947;6436.324185960407;6480.125183100221;4991.8752031198055;6052.788152414644;6342.968285158545;6416.615003099829;6567.44176744982;5615.932972735331;6047.244094488189;6348.492120630431;6378.138951241523;6399.744010239725;6180.443760061616;6319.747210111544;6338.176037115146;6384.233891932998;6431.228252609717;4239.80920858556;6009.969950150221;6067.848303792264;6260.092286618324;6340.080688300079;6383.074454204021;6388.744450222125;6400.327965563735;6410.423061924449;6414.550981431442;6071.2714474263375;6103.05402662588;6137.877242455215;6143.481172719164;6295.307516173126 02:36:11 INFO - PROCESS | 1639 | |1;ext.html;693.9063226464427;801.823598808262;962.1388856973009;969.1084202533668;985.3890587835543;38027;39823.80088099556;39904.40143397848;39918.800405998;40080.79757607269;38315.61684383155;38428.80785596075;38593.61406385942;38627.613723862756;38659.61340386595;7817;9117.635294588208;10452.581896724121;10695.679129626085;10820.350778953249;10634.042936135746;10716.464176791169;10719.78560428791;10721.195910306718;10732.356058636507;10647.361158330486;10709.785804283909;10713.357198568072;10728.35629862207;10735.83896241557;10145.239107067;10680.412577308207;10720.249582529226;10738.248322617419;10751.677449676523;10378.844317335246;10417.062464378243;10473.895261047368;10479.47602619866;10480.161587073017;32554;32990.17524561905;33120.668793312;33174.17064573381;33187.83842565498;3662.8351724172476;3703.370427027412;3768.773873567595;3774.735768496206;3819.5034645495907;24543.509129817303;24634;24836.751632483625;25172.24483265505;25931.611025834627;3017.396520695848;3039.6504401993834;3130.812151270908;3210.807351558914;3243.4161850866835;363996.18001910084;365211;367361;367811.1609441962;375872;35827.10432239163;36469;42565.574344256784;43286.78356608197;43454.78272608617;44998.87502812456;45436;45618.85952851139;45723;45766.313505296785;13313.40089695966;13358.732825343439;13405.329733513285;13444.798328025088;13452.663683407993;11622.302661840316;11632.767344653228;11648.475818588184;11750.471228794724;11780.587679431179;11554.248973816842;11709.941450292694;11717.531298748125;11748.823767643493;11829.822552661717;5804.593678442468;5824.388439213822;5840.065589505659;5846.444587764156;5872.500837428866;1027.4349107990574;1114.5541783286587;1130.7826362484177;1154.5612667186415;1174.8472698549306;21297.509174357645;24036.158734444212;25440;26600.201993940213;27799.72200278012;1219.7621463814648;1250.7373451575272;1272.815441760958;1282.807578863178;1298;11530.423478826026;11901.88098119025;13457.528986485426;13619.236761287711;14049.929750351388;432252;501823;503059;503745;505875;47482.76258618754;47487.525124749;47807.760961194974;47898;47955.280670789965;1388.590365842079;1397.839248486437;1431.427429028397;1431.6063082652145;1432.5702289313372;25316.873415632803;26878;27131.86434067817;27140;27148.32129196786;27759.722402775715;34447.655523444446;34575.65424345724;34839.47740783941;34866.82566587151;447683;447841;450707;452039;452524;23544.646830297217;24027.879860600584;27969.440611187256;28867.711322886502;29091.56362654604;35964.28071438609;36029.45955810612;36492.45261321135;36798;36904.44643330298;30759.692403076577;30788;30891.073267802003;31582;31640;29850.402991939605;32916.50625240576;33688.663113369534;34547.96356109321;34939.82530087333;32461.67538324681;32912.34175316528;33063.83468082644;33173.83413083015;33179;22340.664890026987;28224.576631350137;28707.995220167195;28865.71134288714;29770;22687.659685105067;23021.19425820155;23236.535269294858;23275.88362058179;23385.88307058454 02:36:11 INFO - PROCESS | 1639 | |2;jquery.html;407.9653229475494;412.8596277265729;415.21731536054546;426.4285856951685;452.64241249412964;20708.378748637526;20838.37484875454;21469.57060858786;21548.784512154874;21647.891760541213;21206.939653017365;21787.67318490224;22212.77787222127;22642;22655.433614159614;6779.220389655191;7086.964565177167;7101.076860008192;7119.572825630453;7144.392726618244;6581.80254592363;6933.930660693404;6956.826079348006;6974.651267436633;6987.1266091738535;6785.355391237825;6844.007618895257;6965.582065076112;6966.02475653409;7019.543729657556;5848.88302233953;6583.14419125513;6950.826229344257;7013.368796808286;7044.366007059363;6879.6216208108535;7222.386097181746;7299.598522081284;7335.156457007459;7339.376153026946;24862.87568562164;25323.873380633162;25495.745042549708;25698;25935.092271770587;2882.2506148401358;2903.8112522685956;2905.796594238404;2908.1130255272133;2943.852807359645;15549.922250388789;16131.274092665859;16131.677366452604;16479.258433370407;16577.751333730004;1722.5435259656201;2014.4560968538558;2022.0193206294934;2022.8482863785275;2023.3929821053625;131214;131542;157872;163431;169453;20966.790332096483;21549.24577639775;21737.13051477923;21851.890740546194;21866;31428.057158285286;31712.207194819854;31949.36101278008;31993.840030799696;32024.679753202636;10179.643712470028;10506.106980906708;10637.62768303121;10685.626003089854;10743.623973160902;8881.111888811067;8895.059175675982;8914.108589141164;8919.509426981578;9024.954875225581;9457.763555911157;9503.334766566273;9559.474228917476;9574.664886728931;9633.133018028271;3237.6762323767907;4025.4766880305374;4026.416169655384;4029.3351596986754;4036.414719865603;826.9338452923752;869.1569177897429;899.1143723432414;927.4342650982895;934.6775362499926;16867.156642167844;17921.462356129337;19707.50731231702;20651.17395304201;21495.78504214969;927.7634203278121;936.1761649748178;941.6798288581915;953.4088864903804;956.8182045411489;10978.725531861768;11046.392448415254;11107.777844443066;11109.722256943642;11135.550178960615;693691;712748;721906;753081;774828;123181.15228271583;152443.23778381036;157399.21300393424;158115;158930.20534897482;1269.7206614544784;1299.136074510459;1306.490468717201;1310.2007775257275;1315.355475816843;18106.909465452587;18881.395081417682;18906.43280701581;18911.243550257837;18917.95951222696;62000.68999654973;62521.68739156457;63521.68239158775;63808.68095659492;64333.35666643461;79385;89240.5537972306;89407;92451.53774231086;92586.53706731423;9939.850902236327;10927.235093543693;11461.713457163303;11635.825462618237;11962.58130965412;12900.612981610566;12959.611211663665;13006.869931300565;13126.868731312565;13317.200967941955;10323.741906452398;10703.678889633324;11261.268017578832;11414.315141091229;11426.314421134759;10088.192944564564;10225.74435639115;10301.175905927657;10416.479176041319;10490.370577765358;11213.271137376045;11233.32600044;11347;11435.371054592077;11514.712132196762;8284.627191776504;11753.177277590828;11797;11837.46731397072;12066.336351500751;11132.499037543492;11376;11542.826857596974;11744.236624619372;11756.0007399372 02:36:11 INFO - PROCESS | 1639 | |3;mootools.html;1412.4632639596946;1563.9218039098057;1585.556044307594;1610.9597260068497;1623.7158497262976;10795.352278863264;11014.118870490362;11342.319460832336;11428.828567571492;11442.313461192334;11084.445777711122;11252.774944501105;11275.436228188579;11285.492152853121;11300.15248856335;2389.6773935518654;2410.385351735305;2423.987880060597;2424.3696638873844;2429.9514009719796;2223.5219427823013;2361.5749165150264;2392.1866565367773;2398.940026499334;2409.72288186859;2101.579684063186;2221.3447033125267;2231.787980141884;2235.35174799308;2236.2396785093065;2338.777816107467;2353.329301149173;2354.988225058872;2366.621340585515;2370.6088495398326;2290.324354315481;2356.7525409832065;2367.9171229007075;2379.559781440422;2383.3922349800864;11398.544058237661;11435;11459.484323205475;11459.713507162303;11470.770584588345;4845.685030473008;5133.974330128325;5704.515116215085;5850.619709718854;5906.173135760997;3398.4052790761766;3563.750537462353;3605.2428989911887;3633.128049268183;3658.1037645776955;985.4087547471531;1137.9417142057882;1164.6214980131492;1187.8871507206802;1213.6116442738328;13663.590092297247;13826;15109.093454392772;15393.769093463607;15429.22853857314;4502.617277531446;4604.194266003436;4746.477887432379;4752.263399173134;4763.166445871959;11561.88438115625;11714.88285117138;11765.176437649454;11769.470373833197;11774.411279435993;5652.236948012048;5713.8285851424525;5727.656340619576;5727.7995270166175;5734.627249228787;5136.794528218904;5177.456367081402;5217.191335343028;5245.659032162897;5269.209618557247;3777.54669439669;3807.371783655721;3816;3882.5729169791302;3909.980450097731;844.9112843151504;848.7878030492377;994.7363948553639;1002.4887307473241;1016.7508960304773;805.79049447143;912.4388501071926;920.4339331311329;926.2775035472342;929.5212965322864;5707.429257074343;6298.622082675054;6464.062710906892;6501.187351581052;6551.705173267214;980;1001.8697569316088;1017.5370206556147;1021.6628512590827;1024.36489376587;3339;3761.6990640748672;3961;3988.461557689675;4082.408050832613;13811.792823107662;14202.934779891342;14212.928935355258;14244.145351278956;14245.643858903402;13742.244176570184;13861.960352973365;14083;14220.21788801626;14228.14631122136;9761;10425.478726063666;10425.843612345821;10570.995755403224;10578.206634502443;5339.866503337447;6422.3577642235405;6669.899951500634;6682.532222744556;6730.192376914802;12179.634610961686;12325.75348493043;12380.071494637757;12465.065120116209;12480.75038499243;11082.556697732;11400.429978500875;11642.01042911362;12240.265584064628;12411.255324680547;10269.640562580274;10328.690139295833;10441.895581044093;10457.26799124054;10479.790404192026;8568.657253709782;10300;10327.122194613241;10405.427701476492;10406.47967601632;6930.960355946649;8239.340852731886;8748;9058.954705226432;9361.204297634777;6906.792796216121;7856.096548896721;8661.090585488433;8801.735947921572;9009.189172974464;7691.692332306645;7780.844383112193;7903.328217101611;7907.288344049063;7928.088269849041;4695.929561056519;5426.5387442066285;5606.523445507014;5608.439156084359;5632.633878797865;5679.630823996592;5690.203371527907;5708.0010998076;5713.714314284352;5816.9127463088935 02:36:11 INFO - PROCESS | 1639 | |4;prototype.html;323.469510003594;371.3705269568083;372.6534323079536;384.95957924417934;391.48910671573594;4784.976075119628;5167.689938603687;5273.789048438062;5342.358916929974;5413.431589683086;4877.658563900528;5065.125179237877;5093.643444958854;5145.768440420181;5232.92150617741;3863.9806800966026;4380.430544029272;4380.97809510952;4408.4048653431855;4424.77876106195;3561.7506774525796;3895.590962948892;3976.296118639324;4204.138151678899;4483.417155769762;4402.559744025604;4436.889077773066;4445.533219012005;4447.533009034053;4458;3528.029791807248;3757.924841503182;3868.883933482;4360.694751367406;4392.868213953587;3368.528406023159;3509.4560343146854;3587.641235876418;3647.361711700442;3839.022903204852;4587.495375508692;4642.932125611128;4780.713157210578;5110.616703747197;5114.2584325273;4596.701214421052;5047.939932614156;5073.224371469993;5080.339555857752;5143.614228932845;5412.810551630674;5625.38120806711;5673.099511663598;5701.600887937846;5745.425457454221;4138.448081969262;4419.955800441987;4504.076664283847;4521.299198624186;4583.152116858359;5686.891056244075;5794.188813566104;5907.054871220627;5954.166416701665;5982.188371465404;4057.9797101014306;4170.457840480779;4353.867994321436;4496.820127194876;4701.6003639690325;4183.079722461053;4392.516823149451;4595.151006376068;4786.904261914742;4787.928181077286;4906.533879281534;4925.827596034122;5071.39143302806;5085.830259040367;5156.8968620627375;4786.617070634341;5208.749900023967;5584.459071122629;5591.217229587861;5615.887682246333;1406.4374250299961;1450.2386247220084;1597.8162511311116;1602.6313947792073;1630.8695304375858;1108.0304733358312;1151.9884801151882;1186.608419221672;1188.7087663522493;1191.4638412714314;4444.888877778017;5257.63196576236;5264.6314757966575;5408.783648654088;5433.945660543423;5111.437741848394;5145.485451454824;5883.382244864313;5968.641881487125;5987.856375402948;5047.520485553867;5049.747512624427;5327.375150579033;5372.435894231127;5444.584408053855;2595.701494328139;3053.8931137410086;3060.648025477054;3098.5127138972966;3106.353632574739;5038.949610503922;5357.678539287655;5646.802361917313;5832.5381083471175;6063.333033366326;4659.464161621457;5131.281620573122;5895.64626122434;5965.940340596539;6002.339742628307;4868.050730107666;5036.445990940993;5177.378714554202;5892.40904955651;6099.878002440015;4833.685810422311;5801.6519008859605;5885.676287804212;5905.881882362242;5938.61399009063;5181.404138524118;5449.365190442772;5694.772209111589;5801.82594522165;5833.79581714638;4983.632774432116;6159.059896132704;6172.888880001643;6225.315215326401;6265.718042688181;5010.275461407761;5144.228365745168;5145.089731803781;5165.457626949116;5235.476452354734;5229.431170648866;5246.743900122984;5614.476601354042;5930.581716621145;5957.702114894325;5285.973570132279;5357.705326207096;5587.9824894558105;5719.771209151588;5773.430823283598;5314.847486767865;5353.732313384393;5705.914411283751;5708.257926469532;5745.482906538432;4617.953820461752;5643.407442218508;5652.943470565242;5670.79600025984;5681.545476361798;4598.954010459853;5451.700156491431;5533.391326953952;5717.514011309086;5769.9711501442225;4938.358013458227;5278.81524146653;5343.679379237258;5535.916961245665;5704.572157088318 02:36:11 INFO - PROCESS | 1639 | |5;yui.html;658.4173006888902;716.4411758828115;720.9387202087822;761.3642608421968;850.3749743938206;15069.924650376746;16176;16178.83821161788;16187.91906040471;16238.594035149143;14687.632809179775;14717.779233311481;14730.852691473083;14830.629234269149;14919.403223871032;912.9041450647686;920.5765347939956;925.9074092590754;942.1426501883287;953.3374304858127;949.6581230756922;957;959.9088086631759;960.4189465373453;962.100436092256;985.6057576969234;1000.999000999001;1004.507791182319;1005.0100650858934;1006.3157053203819;997.1175509673955;998.3859926145404;999.0309399882103;1002.04304888831;1002.6139936124608;733.336330620789;735.2610626320567;736.3446532586003;737.2038198745342;737.6348707389876;15890.126043067627;16033.198340083065;16068.919655401765;16766;17048.318067277294;13955;13961.441542338294;14039.508617198451;14182.574522764333;14362.353694083791;11736.471858766376;11915.523379064827;11919.523219071227;11941.10441716866;12005.09961752872;3611.837467313977;3654.8172591370326;3696.8890933272046;3727.9440808387894;3837.6737977271687;116894;117800.41099794446;121550;123593.38203309017;123910;10494.370337779605;10517.631882884063;10839.83740243897;10898.128149748001;10960.506777195045;22208.55582888333;22338.441538961655;22345.664815027787;22386.328410147722;22403.103875844783;6887.55361374107;7516.887246691304;7603.65783539742;7716.92283077162;7725.266099720518;4271.10306835568;4349.782510874444;4414.867553973386;4465.888352791141;4546;3112.8910488132806;3405.4721518164724;3473.0796338970194;3475.94786078209;3527.4356103023392;516.8604476791245;613.3835495327256;620.4973971083347;623.557274335218;636.5289685632598;664.3588936676034;668.1481111582674;695.488815720445;705.1291654806311;729.3253740290211;8634.30925525969;10407.895921040845;10420.332746760481;10497.685069447776;10568.668459316894;691.6852831961445;694.2224708326706;704.1233664088243;707.1443553300461;710.1478226128563;4657.5575320344515;5051.166557517969;5057.570106540985;5065.645404821701;5068.847934561969;78945.81581276286;82469.58765206256;91793.54103229441;93632;93837.06162938284;57605.71197143987;58519.70740146272;67705.48659902385;90727.09272907318;93615;74697;75327.62336188393;77511;78229.60885195651;78333;10919.890801091888;11174.888251117385;11185.105191584817;11479.885201148216;11519.424028798694;46052;47144.29283560818;48068.759656201495;49661.75169124131;51651;64840.675796620715;68124;68685.31314686789;69053.65473172803;71647.64176179086;10788.568457261621;12450.190737602026;12715.55495557651;13028.609141725763;13683.86316136826;14414.279286035866;15166.393344266105;15406.922965385102;15448.768268476206;15576.221188940734;12963.870361296265;13701.45194192221;13758.449662013407;14026.859731402556;14084.295785210903;13052.54316098932;13442.798358024833;13445.798313025116;13469.259190744211;13562.932185339405;12817.423215955117;13599.456021759019;13673.453061877812;13695.246761428218;13708;11003.394813285346;11364.374959377314;11455.140864435034;11557.537698491966;11718.179727419;10509.684709458728;10646.883505241953;11353.82928895298;11491.367974761133;11712.765744685228 02:36:11 INFO - PROCESS | 1639 | __end_tp_report 02:36:11 INFO - PROCESS | 1639 | __start_cc_report 02:36:11 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,424 02:36:11 INFO - PROCESS | 1639 | __end_cc_report 02:36:11 INFO - PROCESS | 1639 | __startTimestamp1490002571186__endTimestamp 02:36:11 INFO - PROCESS | 1639 | 02:36:11 INFO - PROCESS | 1639 | ------- Summary: start ------- 02:36:11 INFO - PROCESS | 1639 | Number of tests: 6 02:36:11 INFO - PROCESS | 1639 | 02:36:11 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5952.46 Median:6236.00 stddev:742.06 (11.9%) stddev-sans-first:702.34 02:36:11 INFO - PROCESS | 1639 | Values: 2815.4 3495.2 3499.4 3502.6 3552.4 4875.3 6041.8 6077.6 6106.3 6108.9 6054.0 6119.1 6124.1 6147.7 6163.5 5417.2 5470.9 5497.1 5519.7 5602.6 5544.6 5545.9 5563.5 5565.2 5571.2 4348.5 4791.9 5312.7 5522.4 5568.1 4535.5 5409.6 5442.9 5501.2 5527.2 5366.7 5473.7 5474.6 5487.5 5502.7 5810.3 6070.9 6159.3 6215.2 6236.8 6308.9 6310.2 6316.6 6372.5 6384.5 6588.8 6610.2 6615.3 6629.1 6630.9 4510.6 5983.8 6192.0 6228.3 6266.1 6349.2 6385.2 6596.2 6695.9 6725.7 5292.7 6390.2 6424.4 6438.9 6442.9 6533.7 6564.6 6572.9 6579.9 6597.0 6312.1 6323.1 6362.7 6444.4 6514.4 6269.0 6465.4 6508.5 6519.9 6531.9 4982.2 5103.8 5209.6 5216.6 5222.9 4624.1 4641.0 4641.1 4668.8 4675.3 5997.7 6070.5 6191.4 6293.4 6331.5 4738.2 6232.1 6352.3 6382.6 6518.9 6236.4 6238.6 6262.5 6263.0 6274.7 5745.5 5752.7 5754.4 5777.8 5799.4 6335.5 6512.4 6647.7 6797.8 6802.9 6530.4 6560.8 6592.0 6592.7 6611.5 6757.8 6768.9 6775.5 6869.2 6960.0 5391.8 6236.0 6288.7 6436.3 6480.1 4991.9 6052.8 6343.0 6416.6 6567.4 5615.9 6047.2 6348.5 6378.1 6399.7 6180.4 6319.7 6338.2 6384.2 6431.2 4239.8 6010.0 6067.8 6260.1 6340.1 6383.1 6388.7 6400.3 6410.4 6414.6 6071.3 6103.1 6137.9 6143.5 6295.3 02:36:11 INFO - PROCESS | 1639 | 02:36:11 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:55353.98 Median:3028.52 stddev:116879.38 (3859.3%) stddev-sans-first:117135.20 02:36:11 INFO - PROCESS | 1639 | Values: 693.9 801.8 962.1 969.1 985.4 38027.0 39823.8 39904.4 39918.8 40080.8 38315.6 38428.8 38593.6 38627.6 38659.6 7817.0 9117.6 10452.6 10695.7 10820.4 10634.0 10716.5 10719.8 10721.2 10732.4 10647.4 10709.8 10713.4 10728.4 10735.8 10145.2 10680.4 10720.2 10738.2 10751.7 10378.8 10417.1 10473.9 10479.5 10480.2 32554.0 32990.2 33120.7 33174.2 33187.8 3662.8 3703.4 3768.8 3774.7 3819.5 24543.5 24634.0 24836.8 25172.2 25931.6 3017.4 3039.7 3130.8 3210.8 3243.4 363996.2 365211.0 367361.0 367811.2 375872.0 35827.1 36469.0 42565.6 43286.8 43454.8 44998.9 45436.0 45618.9 45723.0 45766.3 13313.4 13358.7 13405.3 13444.8 13452.7 11622.3 11632.8 11648.5 11750.5 11780.6 11554.2 11709.9 11717.5 11748.8 11829.8 5804.6 5824.4 5840.1 5846.4 5872.5 1027.4 1114.6 1130.8 1154.6 1174.8 21297.5 24036.2 25440.0 26600.2 27799.7 1219.8 1250.7 1272.8 1282.8 1298.0 11530.4 11901.9 13457.5 13619.2 14049.9 432252.0 501823.0 503059.0 503745.0 505875.0 47482.8 47487.5 47807.8 47898.0 47955.3 1388.6 1397.8 1431.4 1431.6 1432.6 25316.9 26878.0 27131.9 27140.0 27148.3 27759.7 34447.7 34575.7 34839.5 34866.8 447683.0 447841.0 450707.0 452039.0 452524.0 23544.6 24027.9 27969.4 28867.7 29091.6 35964.3 36029.5 36492.5 36798.0 36904.4 30759.7 30788.0 30891.1 31582.0 31640.0 29850.4 32916.5 33688.7 34548.0 34939.8 32461.7 32912.3 33063.8 33173.8 33179.0 22340.7 28224.6 28708.0 28865.7 29770.0 22687.7 23021.2 23236.5 23275.9 23385.9 02:36:11 INFO - PROCESS | 1639 | 02:36:11 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:42417.30 Median:22427.39 stddev:122130.09 (544.6%) stddev-sans-first:122431.97 02:36:11 INFO - PROCESS | 1639 | Values: 408.0 412.9 415.2 426.4 452.6 20708.4 20838.4 21469.6 21548.8 21647.9 21206.9 21787.7 22212.8 22642.0 22655.4 6779.2 7087.0 7101.1 7119.6 7144.4 6581.8 6933.9 6956.8 6974.7 6987.1 6785.4 6844.0 6965.6 6966.0 7019.5 5848.9 6583.1 6950.8 7013.4 7044.4 6879.6 7222.4 7299.6 7335.2 7339.4 24862.9 25323.9 25495.7 25698.0 25935.1 2882.3 2903.8 2905.8 2908.1 2943.9 15549.9 16131.3 16131.7 16479.3 16577.8 1722.5 2014.5 2022.0 2022.8 2023.4 131214.0 131542.0 157872.0 163431.0 169453.0 20966.8 21549.2 21737.1 21851.9 21866.0 31428.1 31712.2 31949.4 31993.8 32024.7 10179.6 10506.1 10637.6 10685.6 10743.6 8881.1 8895.1 8914.1 8919.5 9025.0 9457.8 9503.3 9559.5 9574.7 9633.1 3237.7 4025.5 4026.4 4029.3 4036.4 826.9 869.2 899.1 927.4 934.7 16867.2 17921.5 19707.5 20651.2 21495.8 927.8 936.2 941.7 953.4 956.8 10978.7 11046.4 11107.8 11109.7 11135.6 693691.0 712748.0 721906.0 753081.0 774828.0 123181.2 152443.2 157399.2 158115.0 158930.2 1269.7 1299.1 1306.5 1310.2 1315.4 18106.9 18881.4 18906.4 18911.2 18918.0 62000.7 62521.7 63521.7 63808.7 64333.4 79385.0 89240.6 89407.0 92451.5 92586.5 9939.9 10927.2 11461.7 11635.8 11962.6 12900.6 12959.6 13006.9 13126.9 13317.2 10323.7 10703.7 11261.3 11414.3 11426.3 10088.2 10225.7 10301.2 10416.5 10490.4 11213.3 11233.3 11347.0 11435.4 11514.7 8284.6 11753.2 11797.0 11837.5 12066.3 11132.5 11376.0 11542.8 11744.2 11756.0 02:36:11 INFO - PROCESS | 1639 | 02:36:11 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:6700.97 Median:2390.93 stddev:4284.66 (179.2%) stddev-sans-first:4278.25 02:36:11 INFO - PROCESS | 1639 | Values: 1412.5 1563.9 1585.6 1611.0 1623.7 10795.4 11014.1 11342.3 11428.8 11442.3 11084.4 11252.8 11275.4 11285.5 11300.2 2389.7 2410.4 2424.0 2424.4 2430.0 2223.5 2361.6 2392.2 2398.9 2409.7 2101.6 2221.3 2231.8 2235.4 2236.2 2338.8 2353.3 2355.0 2366.6 2370.6 2290.3 2356.8 2367.9 2379.6 2383.4 11398.5 11435.0 11459.5 11459.7 11470.8 4845.7 5134.0 5704.5 5850.6 5906.2 3398.4 3563.8 3605.2 3633.1 3658.1 985.4 1137.9 1164.6 1187.9 1213.6 13663.6 13826.0 15109.1 15393.8 15429.2 4502.6 4604.2 4746.5 4752.3 4763.2 11561.9 11714.9 11765.2 11769.5 11774.4 5652.2 5713.8 5727.7 5727.8 5734.6 5136.8 5177.5 5217.2 5245.7 5269.2 3777.5 3807.4 3816.0 3882.6 3910.0 844.9 848.8 994.7 1002.5 1016.8 805.8 912.4 920.4 926.3 929.5 5707.4 6298.6 6464.1 6501.2 6551.7 980.0 1001.9 1017.5 1021.7 1024.4 3339.0 3761.7 3961.0 3988.5 4082.4 13811.8 14202.9 14212.9 14244.1 14245.6 13742.2 13862.0 14083.0 14220.2 14228.1 9761.0 10425.5 10425.8 10571.0 10578.2 5339.9 6422.4 6669.9 6682.5 6730.2 12179.6 12325.8 12380.1 12465.1 12480.8 11082.6 11400.4 11642.0 12240.3 12411.3 10269.6 10328.7 10441.9 10457.3 10479.8 8568.7 10300.0 10327.1 10405.4 10406.5 6931.0 8239.3 8748.0 9059.0 9361.2 6906.8 7856.1 8661.1 8801.7 9009.2 7691.7 7780.8 7903.3 7907.3 7928.1 4695.9 5426.5 5606.5 5608.4 5632.6 5679.6 5690.2 5708.0 5713.7 5816.9 02:36:11 INFO - PROCESS | 1639 | 02:36:11 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4710.62 Median:5112.85 stddev:1332.72 (26.1%) stddev-sans-first:1295.14 02:36:11 INFO - PROCESS | 1639 | Values: 323.5 371.4 372.7 385.0 391.5 4785.0 5167.7 5273.8 5342.4 5413.4 4877.7 5065.1 5093.6 5145.8 5232.9 3864.0 4380.4 4381.0 4408.4 4424.8 3561.8 3895.6 3976.3 4204.1 4483.4 4402.6 4436.9 4445.5 4447.5 4458.0 3528.0 3757.9 3868.9 4360.7 4392.9 3368.5 3509.5 3587.6 3647.4 3839.0 4587.5 4642.9 4780.7 5110.6 5114.3 4596.7 5047.9 5073.2 5080.3 5143.6 5412.8 5625.4 5673.1 5701.6 5745.4 4138.4 4420.0 4504.1 4521.3 4583.2 5686.9 5794.2 5907.1 5954.2 5982.2 4058.0 4170.5 4353.9 4496.8 4701.6 4183.1 4392.5 4595.2 4786.9 4787.9 4906.5 4925.8 5071.4 5085.8 5156.9 4786.6 5208.7 5584.5 5591.2 5615.9 1406.4 1450.2 1597.8 1602.6 1630.9 1108.0 1152.0 1186.6 1188.7 1191.5 4444.9 5257.6 5264.6 5408.8 5433.9 5111.4 5145.5 5883.4 5968.6 5987.9 5047.5 5049.7 5327.4 5372.4 5444.6 2595.7 3053.9 3060.6 3098.5 3106.4 5038.9 5357.7 5646.8 5832.5 6063.3 4659.5 5131.3 5895.6 5965.9 6002.3 4868.1 5036.4 5177.4 5892.4 6099.9 4833.7 5801.7 5885.7 5905.9 5938.6 5181.4 5449.4 5694.8 5801.8 5833.8 4983.6 6159.1 6172.9 6225.3 6265.7 5010.3 5144.2 5145.1 5165.5 5235.5 5229.4 5246.7 5614.5 5930.6 5957.7 5286.0 5357.7 5588.0 5719.8 5773.4 5314.8 5353.7 5705.9 5708.3 5745.5 4618.0 5643.4 5652.9 5670.8 5681.5 4599.0 5451.7 5533.4 5717.5 5770.0 4938.4 5278.8 5343.7 5535.9 5704.6 02:36:11 INFO - PROCESS | 1639 | 02:36:11 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20170.11 Median:3259.18 stddev:28763.97 (882.6%) stddev-sans-first:28807.35 02:36:11 INFO - PROCESS | 1639 | Values: 658.4 716.4 720.9 761.4 850.4 15069.9 16176.0 16178.8 16187.9 16238.6 14687.6 14717.8 14730.9 14830.6 14919.4 912.9 920.6 925.9 942.1 953.3 949.7 957.0 959.9 960.4 962.1 985.6 1001.0 1004.5 1005.0 1006.3 997.1 998.4 999.0 1002.0 1002.6 733.3 735.3 736.3 737.2 737.6 15890.1 16033.2 16068.9 16766.0 17048.3 13955.0 13961.4 14039.5 14182.6 14362.4 11736.5 11915.5 11919.5 11941.1 12005.1 3611.8 3654.8 3696.9 3727.9 3837.7 116894.0 117800.4 121550.0 123593.4 123910.0 10494.4 10517.6 10839.8 10898.1 10960.5 22208.6 22338.4 22345.7 22386.3 22403.1 6887.6 7516.9 7603.7 7716.9 7725.3 4271.1 4349.8 4414.9 4465.9 4546.0 3112.9 3405.5 3473.1 3475.9 3527.4 516.9 613.4 620.5 623.6 636.5 664.4 668.1 695.5 705.1 729.3 8634.3 10407.9 10420.3 10497.7 10568.7 691.7 694.2 704.1 707.1 710.1 4657.6 5051.2 5057.6 5065.6 5068.8 78945.8 82469.6 91793.5 93632.0 93837.1 57605.7 58519.7 67705.5 90727.1 93615.0 74697.0 75327.6 77511.0 78229.6 78333.0 10919.9 11174.9 11185.1 11479.9 11519.4 46052.0 47144.3 48068.8 49661.8 51651.0 64840.7 68124.0 68685.3 69053.7 71647.6 10788.6 12450.2 12715.6 13028.6 13683.9 14414.3 15166.4 15406.9 15448.8 15576.2 12963.9 13701.5 13758.4 14026.9 14084.3 13052.5 13442.8 13445.8 13469.3 13562.9 12817.4 13599.5 13673.5 13695.2 13708.0 11003.4 11364.4 11455.1 11557.5 11718.2 10509.7 10646.9 11353.8 11491.4 11712.8 02:36:11 INFO - PROCESS | 1639 | -------- Summary: end -------- 02:36:11 INFO - PROCESS | 1639 | 02:36:11 INFO - TEST-INFO | 1639: exit 0 02:36:11 INFO - TEST-OK | dromaeo_css | took 1117361ms 02:36:11 INFO - TEST-START | kraken 02:36:11 INFO - operating with platform_type : mac_ 02:36:11 INFO - Initialising browser for kraken test... 02:36:11 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpLhFwDY/profile http://localhost:49238/getInfo.html) 02:36:14 INFO - PROCESS | 1652 | 2017-03-20 02:36:14.487 plugin-container[1654:10161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c47, name = 'com.apple.tsm.portname' 02:36:14 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:36:14 INFO - PROCESS | 1652 | 2017-03-20 02:36:14.487 plugin-container[1654:10161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 02:36:14 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:36:14 INFO - PROCESS | 1652 | 2017-03-20 02:36:14.487 plugin-container[1654:10161] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:36:14 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 02:36:14 INFO - PROCESS | 1652 | colorDepth:24 02:36:14 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 02:36:14 INFO - PROCESS | 1652 | __metrics 02:36:14 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 02:36:15 INFO - TEST-INFO | 1652: exit 0 02:36:15 INFO - Browser initialized. 02:36:15 INFO - Running cycle 1/1 for kraken test... 02:36:15 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpLhFwDY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:36:17 INFO - PROCESS | 1655 | 2017-03-20 02:36:17.917 plugin-container[1656:10290] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 02:36:17 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:36:17 INFO - PROCESS | 1655 | 2017-03-20 02:36:17.917 plugin-container[1656:10290] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 02:36:17 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:36:17 INFO - PROCESS | 1655 | 2017-03-20 02:36:17.918 plugin-container[1656:10290] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:36:27 INFO - PROCESS | 1655 | RSS: Main: 220323840 02:36:27 INFO - PROCESS | 1655 | 02:36:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection) 02:36:31 INFO - PROCESS | 1655 | RSS: Main: 235204608 02:36:31 INFO - PROCESS | 1655 | 02:36:34 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-dft) 02:36:34 INFO - PROCESS | 1655 | RSS: Main: 243376128 02:36:34 INFO - PROCESS | 1655 | 02:36:37 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-fft) 02:36:38 INFO - PROCESS | 1655 | RSS: Main: 247037952 02:36:38 INFO - PROCESS | 1655 | 02:36:40 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator) 02:36:41 INFO - PROCESS | 1655 | RSS: Main: 248705024 02:36:41 INFO - PROCESS | 1655 | 02:36:42 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:36:43 INFO - PROCESS | 1655 | RSS: Main: 248803328 02:36:43 INFO - PROCESS | 1655 | 02:36:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom) 02:36:47 INFO - PROCESS | 1655 | RSS: Main: 253014016 02:36:47 INFO - PROCESS | 1655 | 02:36:50 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate) 02:36:51 INFO - PROCESS | 1655 | RSS: Main: 252162048 02:36:51 INFO - PROCESS | 1655 | 02:36:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial) 02:36:55 INFO - PROCESS | 1655 | RSS: Main: 249868288 02:36:55 INFO - PROCESS | 1655 | 02:36:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:36:57 INFO - PROCESS | 1655 | RSS: Main: 250634240 02:36:57 INFO - PROCESS | 1655 | 02:36:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:37:00 INFO - PROCESS | 1655 | RSS: Main: 251023360 02:37:00 INFO - PROCESS | 1655 | 02:37:02 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:37:02 INFO - PROCESS | 1655 | RSS: Main: 251523072 02:37:02 INFO - PROCESS | 1655 | 02:37:05 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:37:06 INFO - PROCESS | 1655 | RSS: Main: 251695104 02:37:06 INFO - PROCESS | 1655 | 02:37:09 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:37:10 INFO - PROCESS | 1655 | RSS: Main: 251482112 02:37:10 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49238/tests/kraken/driver.html?testName=ai-astar) 02:37:12 INFO - PROCESS | 1655 | RSS: Main: 253468672 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | __start_tp_report 02:37:12 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 02:37:12 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 02:37:12 INFO - PROCESS | 1655 | |i|pagename|runs| 02:37:12 INFO - PROCESS | 1655 | |0;ai-astar;107;119;121;121;120;121;120;114;120;119 02:37:12 INFO - PROCESS | 1655 | |1;audio-beat-detection;136;115;111;145;114;113;110;115;129;116 02:37:12 INFO - PROCESS | 1655 | |2;audio-dft;196;200;196;196;200;196;194;199;201;196 02:37:12 INFO - PROCESS | 1655 | |3;audio-fft;86;60;61;87;63;58;60;60;71;61 02:37:12 INFO - PROCESS | 1655 | |4;audio-oscillator;87;70;87;73;72;71;81;70;62;79 02:37:12 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;89;90;97;88;102;87;135;93;137;92 02:37:12 INFO - PROCESS | 1655 | |6;imaging-darkroom;121;110;155;134;96;98;98;98;99;97 02:37:12 INFO - PROCESS | 1655 | |7;imaging-desaturate;144;87;139;150;93;117;140;102;139;142 02:37:12 INFO - PROCESS | 1655 | |8;json-parse-financial;66;65;68;69;70;70;70;69;70;70 02:37:12 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;59;82;88;98;65;70;56;58;58;60 02:37:12 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;90;106;83;87;87;83;84;83;86;86 02:37:12 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;148;151;151;146;163;149;141;157;209;176 02:37:12 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;189;226;151;223;234;219;168;164;162;226 02:37:12 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;67;66;70;79;82;86;89;90;65;67 02:37:12 INFO - PROCESS | 1655 | __end_tp_report 02:37:12 INFO - PROCESS | 1655 | __start_cc_report 02:37:12 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1019 02:37:12 INFO - PROCESS | 1655 | __end_cc_report 02:37:12 INFO - PROCESS | 1655 | __startTimestamp1490002632603__endTimestamp 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | ------- Summary: start ------- 02:37:12 INFO - PROCESS | 1655 | Number of tests: 14 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:118.20 Median:120.00 stddev:4.44 (3.7%) stddev-sans-first:2.19 02:37:12 INFO - PROCESS | 1655 | Values: 107.0 119.0 121.0 121.0 120.0 121.0 120.0 114.0 120.0 119.0 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:120.40 Median:115.50 stddev:11.98 (10.4%) stddev-sans-first:11.30 02:37:12 INFO - PROCESS | 1655 | Values: 136.0 115.0 111.0 145.0 114.0 113.0 110.0 115.0 129.0 116.0 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:197.40 Median:197.50 stddev:2.37 (1.2%) stddev-sans-first:2.46 02:37:12 INFO - PROCESS | 1655 | Values: 196.0 200.0 196.0 196.0 200.0 196.0 194.0 199.0 201.0 196.0 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:66.70 Median:62.00 stddev:11.02 (17.8%) stddev-sans-first:9.21 02:37:12 INFO - PROCESS | 1655 | Values: 86.0 60.0 61.0 87.0 63.0 58.0 60.0 60.0 71.0 61.0 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:76.00 stddev:8.08 (10.6%) stddev-sans-first:7.36 02:37:12 INFO - PROCESS | 1655 | Values: 87.0 70.0 87.0 73.0 72.0 71.0 81.0 70.0 62.0 79.0 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:101.00 Median:89.50 stddev:18.99 (21.2%) stddev-sans-first:19.63 02:37:12 INFO - PROCESS | 1655 | Values: 89.0 90.0 97.0 88.0 102.0 87.0 135.0 93.0 137.0 92.0 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:110.60 Median:97.50 stddev:20.10 (20.6%) stddev-sans-first:20.96 02:37:12 INFO - PROCESS | 1655 | Values: 121.0 110.0 155.0 134.0 96.0 98.0 98.0 98.0 99.0 97.0 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:125.30 Median:143.00 stddev:23.46 (16.4%) stddev-sans-first:23.88 02:37:12 INFO - PROCESS | 1655 | Values: 144.0 87.0 139.0 150.0 93.0 117.0 140.0 102.0 139.0 142.0 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:68.70 Median:70.00 stddev:1.83 (2.6%) stddev-sans-first:1.66 02:37:12 INFO - PROCESS | 1655 | Values: 66.0 65.0 68.0 69.0 70.0 70.0 70.0 69.0 70.0 70.0 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:67.50 stddev:14.83 (22.0%) stddev-sans-first:15.24 02:37:12 INFO - PROCESS | 1655 | Values: 59.0 82.0 88.0 98.0 65.0 70.0 56.0 58.0 58.0 60.0 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:86.00 stddev:6.88 (8.0%) stddev-sans-first:7.24 02:37:12 INFO - PROCESS | 1655 | Values: 90.0 106.0 83.0 87.0 87.0 83.0 84.0 83.0 86.0 86.0 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:159.10 Median:154.00 stddev:20.14 (13.1%) stddev-sans-first:20.96 02:37:12 INFO - PROCESS | 1655 | Values: 148.0 151.0 151.0 146.0 163.0 149.0 141.0 157.0 209.0 176.0 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.20 Median:221.00 stddev:32.56 (14.7%) stddev-sans-first:34.43 02:37:12 INFO - PROCESS | 1655 | Values: 189.0 226.0 151.0 223.0 234.0 219.0 168.0 164.0 162.0 226.0 02:37:12 INFO - PROCESS | 1655 | 02:37:12 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.10 Median:80.50 stddev:10.16 (12.6%) stddev-sans-first:10.23 02:37:12 INFO - PROCESS | 1655 | Values: 67.0 66.0 70.0 79.0 82.0 86.0 89.0 90.0 65.0 67.0 02:37:12 INFO - PROCESS | 1655 | -------- Summary: end -------- 02:37:12 INFO - PROCESS | 1655 | 02:37:13 INFO - TEST-INFO | 1655: exit 0 02:37:13 INFO - TEST-OK | kraken | took 61666ms 02:37:13 INFO - SUITE-END | took 1179s 02:37:13 INFO - Completed test suite (00:19:39) 02:37:13 INFO - javascript benchmark 02:37:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7677.687923614824, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2815.4369126174765, 3495.1961048958724, 3499.4051011328056, 3502.5796904371477, 3552.431610942248, 4875.341828853104, 6041.818745437638, 6077.6049556778835, 6106.297775755789, 6108.877822443548, 6053.969730151354, 6119.112728654342, 6124.142620033199, 6147.661878596676, 6163.537734669906, 5417.241586177938, 5470.91793623094, 5497.092979658351, 5519.724013799314, 5602.579806514527, 5544.584156188282, 5545.916811247815, 5563.471470210324, 5565.165225216229, 5571.192177134313, 4348.478182618091, 4791.928121078186, 5312.681239125645, 5522.392536820947, 5568.136938774496, 4535.455745310567, 5409.5672346212405, 5442.945570544283, 5501.229827824068, 5527.198556209327, 5366.651167674088, 5473.67157970519, 5474.589405794582, 5487.478689524529, 5502.697351645658, 5810.302763668345, 6070.908936365914, 6159.291681456645, 6215.192025036717, 6236.812895613093, 6308.905366419507, 6310.242770867525, 6316.62100273985, 6372.490200783926, 6384.457321127709, 6588.835279118012, 6610.239822420435, 6615.305392933721, 6629.138212032453, 6630.900536491957, 4510.571495707903, 5983.833152535258, 6192.0, 6228.34602366754, 6266.091416744546, 6349.238091428966, 6385.169927909342, 6596.208454985433, 6695.866082678319, 6725.730970761213, 5292.735363231823, 6390.233172019387, 6424.357564243632, 6438.935610643927, 6442.871142577123, 6533.738650453929, 6564.573302735403, 6572.934270657328, 6579.934200657932, 6596.967015164893, 6312.147860038928, 6323.051542268605, 6362.745490180433, 6444.420002199825, 6514.413702766774, 6268.968655156787, 6465.3857883501, 6508.511861610398, 6519.902201466982, 6531.934680653228, 4982.153033984231, 5103.8468845934685, 5209.609279304067, 5216.634835561475, 5222.947770522323, 4624.075184963021, 4641.0, 4641.094986477605, 4668.766561671903, 4675.275332323496, 5997.730102145327, 6070.453659170696, 6191.380861913773, 6293.402126797947, 6331.461825744771, 4738.19450693383, 6232.127502149703, 6352.2694890087305, 6382.617042977344, 6518.934810651927, 6236.438720515175, 6238.563300569007, 6262.53031022666, 6263.0, 6274.6548939808745, 5745.540356771532, 5752.654840709571, 5754.424557544212, 5777.768889244383, 5799.3620701722775, 6335.524835637253, 6512.381323774234, 6647.7008534617025, 6797.819529817372, 6802.897956530656, 6530.379613936669, 6560.770373036921, 6591.978243372285, 6592.70332835014, 6611.504137189634, 6757.763478278237, 6768.932310676831, 6775.525713200029, 6869.244383117753, 6959.990801333879, 5391.838244852661, 6236.0, 6288.748450061947, 6436.324185960407, 6480.125183100221, 4991.8752031198055, 6052.788152414644, 6342.968285158545, 6416.615003099829, 6567.44176744982, 5615.932972735331, 6047.244094488189, 6348.492120630431, 6378.138951241523, 6399.744010239725, 6180.443760061616, 6319.747210111544, 6338.176037115146, 6384.233891932998, 6431.228252609717, 4239.80920858556, 6009.969950150221, 6067.848303792264, 6260.092286618324, 6340.080688300079, 6383.074454204021, 6388.744450222125, 6400.327965563735, 6410.423061924449, 6414.550981431442, 6071.2714474263375, 6103.05402662588, 6137.877242455215, 6143.481172719164, 6295.307516173126], "value": 5906.549881618886, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.9063226464427, 801.823598808262, 962.1388856973009, 969.1084202533668, 985.3890587835543, 38027.0, 39823.80088099556, 39904.40143397848, 39918.800405998, 40080.79757607269, 38315.61684383155, 38428.80785596075, 38593.61406385942, 38627.613723862756, 38659.61340386595, 7817.0, 9117.635294588208, 10452.581896724121, 10695.679129626085, 10820.350778953249, 10634.042936135746, 10716.464176791169, 10719.78560428791, 10721.195910306718, 10732.356058636507, 10647.361158330486, 10709.785804283909, 10713.357198568072, 10728.35629862207, 10735.83896241557, 10145.239107067, 10680.412577308207, 10720.249582529226, 10738.248322617419, 10751.677449676523, 10378.844317335246, 10417.062464378243, 10473.895261047368, 10479.47602619866, 10480.161587073017, 32554.0, 32990.17524561905, 33120.668793312, 33174.17064573381, 33187.83842565498, 3662.8351724172476, 3703.370427027412, 3768.773873567595, 3774.735768496206, 3819.5034645495907, 24543.509129817303, 24634.0, 24836.751632483625, 25172.24483265505, 25931.611025834627, 3017.396520695848, 3039.6504401993834, 3130.812151270908, 3210.807351558914, 3243.4161850866835, 363996.18001910084, 365211.0, 367361.0, 367811.1609441962, 375872.0, 35827.10432239163, 36469.0, 42565.574344256784, 43286.78356608197, 43454.78272608617, 44998.87502812456, 45436.0, 45618.85952851139, 45723.0, 45766.313505296785, 13313.40089695966, 13358.732825343439, 13405.329733513285, 13444.798328025088, 13452.663683407993, 11622.302661840316, 11632.767344653228, 11648.475818588184, 11750.471228794724, 11780.587679431179, 11554.248973816842, 11709.941450292694, 11717.531298748125, 11748.823767643493, 11829.822552661717, 5804.593678442468, 5824.388439213822, 5840.065589505659, 5846.444587764156, 5872.500837428866, 1027.4349107990574, 1114.5541783286587, 1130.7826362484177, 1154.5612667186415, 1174.8472698549306, 21297.509174357645, 24036.158734444212, 25440.0, 26600.201993940213, 27799.72200278012, 1219.7621463814648, 1250.7373451575272, 1272.815441760958, 1282.807578863178, 1298.0, 11530.423478826026, 11901.88098119025, 13457.528986485426, 13619.236761287711, 14049.929750351388, 432252.0, 501823.0, 503059.0, 503745.0, 505875.0, 47482.76258618754, 47487.525124749, 47807.760961194974, 47898.0, 47955.280670789965, 1388.590365842079, 1397.839248486437, 1431.427429028397, 1431.6063082652145, 1432.5702289313372, 25316.873415632803, 26878.0, 27131.86434067817, 27140.0, 27148.32129196786, 27759.722402775715, 34447.655523444446, 34575.65424345724, 34839.47740783941, 34866.82566587151, 447683.0, 447841.0, 450707.0, 452039.0, 452524.0, 23544.646830297217, 24027.879860600584, 27969.440611187256, 28867.711322886502, 29091.56362654604, 35964.28071438609, 36029.45955810612, 36492.45261321135, 36798.0, 36904.44643330298, 30759.692403076577, 30788.0, 30891.073267802003, 31582.0, 31640.0, 29850.402991939605, 32916.50625240576, 33688.663113369534, 34547.96356109321, 34939.82530087333, 32461.67538324681, 32912.34175316528, 33063.83468082644, 33173.83413083015, 33179.0, 22340.664890026987, 28224.576631350137, 28707.995220167195, 28865.71134288714, 29770.0, 22687.659685105067, 23021.19425820155, 23236.535269294858, 23275.88362058179, 23385.88307058454], "value": 17985.513252816516, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.9653229475494, 412.8596277265729, 415.21731536054546, 426.4285856951685, 452.64241249412964, 20708.378748637526, 20838.37484875454, 21469.57060858786, 21548.784512154874, 21647.891760541213, 21206.939653017365, 21787.67318490224, 22212.77787222127, 22642.0, 22655.433614159614, 6779.220389655191, 7086.964565177167, 7101.076860008192, 7119.572825630453, 7144.392726618244, 6581.80254592363, 6933.930660693404, 6956.826079348006, 6974.651267436633, 6987.1266091738535, 6785.355391237825, 6844.007618895257, 6965.582065076112, 6966.02475653409, 7019.543729657556, 5848.88302233953, 6583.14419125513, 6950.826229344257, 7013.368796808286, 7044.366007059363, 6879.6216208108535, 7222.386097181746, 7299.598522081284, 7335.156457007459, 7339.376153026946, 24862.87568562164, 25323.873380633162, 25495.745042549708, 25698.0, 25935.092271770587, 2882.2506148401358, 2903.8112522685956, 2905.796594238404, 2908.1130255272133, 2943.852807359645, 15549.922250388789, 16131.274092665859, 16131.677366452604, 16479.258433370407, 16577.751333730004, 1722.5435259656201, 2014.4560968538558, 2022.0193206294934, 2022.8482863785275, 2023.3929821053625, 131214.0, 131542.0, 157872.0, 163431.0, 169453.0, 20966.790332096483, 21549.24577639775, 21737.13051477923, 21851.890740546194, 21866.0, 31428.057158285286, 31712.207194819854, 31949.36101278008, 31993.840030799696, 32024.679753202636, 10179.643712470028, 10506.106980906708, 10637.62768303121, 10685.626003089854, 10743.623973160902, 8881.111888811067, 8895.059175675982, 8914.108589141164, 8919.509426981578, 9024.954875225581, 9457.763555911157, 9503.334766566273, 9559.474228917476, 9574.664886728931, 9633.133018028271, 3237.6762323767907, 4025.4766880305374, 4026.416169655384, 4029.3351596986754, 4036.414719865603, 826.9338452923752, 869.1569177897429, 899.1143723432414, 927.4342650982895, 934.6775362499926, 16867.156642167844, 17921.462356129337, 19707.50731231702, 20651.17395304201, 21495.78504214969, 927.7634203278121, 936.1761649748178, 941.6798288581915, 953.4088864903804, 956.8182045411489, 10978.725531861768, 11046.392448415254, 11107.777844443066, 11109.722256943642, 11135.550178960615, 693691.0, 712748.0, 721906.0, 753081.0, 774828.0, 123181.15228271583, 152443.23778381036, 157399.21300393424, 158115.0, 158930.20534897482, 1269.7206614544784, 1299.136074510459, 1306.490468717201, 1310.2007775257275, 1315.355475816843, 18106.909465452587, 18881.395081417682, 18906.43280701581, 18911.243550257837, 18917.95951222696, 62000.68999654973, 62521.68739156457, 63521.68239158775, 63808.68095659492, 64333.35666643461, 79385.0, 89240.5537972306, 89407.0, 92451.53774231086, 92586.53706731423, 9939.850902236327, 10927.235093543693, 11461.713457163303, 11635.825462618237, 11962.58130965412, 12900.612981610566, 12959.611211663665, 13006.869931300565, 13126.868731312565, 13317.200967941955, 10323.741906452398, 10703.678889633324, 11261.268017578832, 11414.315141091229, 11426.314421134759, 10088.192944564564, 10225.74435639115, 10301.175905927657, 10416.479176041319, 10490.370577765358, 11213.271137376045, 11233.32600044, 11347.0, 11435.371054592077, 11514.712132196762, 8284.627191776504, 11753.177277590828, 11797.0, 11837.46731397072, 12066.336351500751, 11132.499037543492, 11376.0, 11542.826857596974, 11744.236624619372, 11756.0007399372], "value": 11549.013919317922, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1412.4632639596946, 1563.9218039098057, 1585.556044307594, 1610.9597260068497, 1623.7158497262976, 10795.352278863264, 11014.118870490362, 11342.319460832336, 11428.828567571492, 11442.313461192334, 11084.445777711122, 11252.774944501105, 11275.436228188579, 11285.492152853121, 11300.15248856335, 2389.6773935518654, 2410.385351735305, 2423.987880060597, 2424.3696638873844, 2429.9514009719796, 2223.5219427823013, 2361.5749165150264, 2392.1866565367773, 2398.940026499334, 2409.72288186859, 2101.579684063186, 2221.3447033125267, 2231.787980141884, 2235.35174799308, 2236.2396785093065, 2338.777816107467, 2353.329301149173, 2354.988225058872, 2366.621340585515, 2370.6088495398326, 2290.324354315481, 2356.7525409832065, 2367.9171229007075, 2379.559781440422, 2383.3922349800864, 11398.544058237661, 11435.0, 11459.484323205475, 11459.713507162303, 11470.770584588345, 4845.685030473008, 5133.974330128325, 5704.515116215085, 5850.619709718854, 5906.173135760997, 3398.4052790761766, 3563.750537462353, 3605.2428989911887, 3633.128049268183, 3658.1037645776955, 985.4087547471531, 1137.9417142057882, 1164.6214980131492, 1187.8871507206802, 1213.6116442738328, 13663.590092297247, 13826.0, 15109.093454392772, 15393.769093463607, 15429.22853857314, 4502.617277531446, 4604.194266003436, 4746.477887432379, 4752.263399173134, 4763.166445871959, 11561.88438115625, 11714.88285117138, 11765.176437649454, 11769.470373833197, 11774.411279435993, 5652.236948012048, 5713.8285851424525, 5727.656340619576, 5727.7995270166175, 5734.627249228787, 5136.794528218904, 5177.456367081402, 5217.191335343028, 5245.659032162897, 5269.209618557247, 3777.54669439669, 3807.371783655721, 3816.0, 3882.5729169791302, 3909.980450097731, 844.9112843151504, 848.7878030492377, 994.7363948553639, 1002.4887307473241, 1016.7508960304773, 805.79049447143, 912.4388501071926, 920.4339331311329, 926.2775035472342, 929.5212965322864, 5707.429257074343, 6298.622082675054, 6464.062710906892, 6501.187351581052, 6551.705173267214, 980.0, 1001.8697569316088, 1017.5370206556147, 1021.6628512590827, 1024.36489376587, 3339.0, 3761.6990640748672, 3961.0, 3988.461557689675, 4082.408050832613, 13811.792823107662, 14202.934779891342, 14212.928935355258, 14244.145351278956, 14245.643858903402, 13742.244176570184, 13861.960352973365, 14083.0, 14220.21788801626, 14228.14631122136, 9761.0, 10425.478726063666, 10425.843612345821, 10570.995755403224, 10578.206634502443, 5339.866503337447, 6422.3577642235405, 6669.899951500634, 6682.532222744556, 6730.192376914802, 12179.634610961686, 12325.75348493043, 12380.071494637757, 12465.065120116209, 12480.75038499243, 11082.556697732, 11400.429978500875, 11642.01042911362, 12240.265584064628, 12411.255324680547, 10269.640562580274, 10328.690139295833, 10441.895581044093, 10457.26799124054, 10479.790404192026, 8568.657253709782, 10300.0, 10327.122194613241, 10405.427701476492, 10406.47967601632, 6930.960355946649, 8239.340852731886, 8748.0, 9058.954705226432, 9361.204297634777, 6906.792796216121, 7856.096548896721, 8661.090585488433, 8801.735947921572, 9009.189172974464, 7691.692332306645, 7780.844383112193, 7903.328217101611, 7907.288344049063, 7928.088269849041, 4695.929561056519, 5426.5387442066285, 5606.523445507014, 5608.439156084359, 5632.633878797865, 5679.630823996592, 5690.203371527907, 5708.0010998076, 5713.714314284352, 5816.9127463088935], "value": 5058.825791757779, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.469510003594, 371.3705269568083, 372.6534323079536, 384.95957924417934, 391.48910671573594, 4784.976075119628, 5167.689938603687, 5273.789048438062, 5342.358916929974, 5413.431589683086, 4877.658563900528, 5065.125179237877, 5093.643444958854, 5145.768440420181, 5232.92150617741, 3863.9806800966026, 4380.430544029272, 4380.97809510952, 4408.4048653431855, 4424.77876106195, 3561.7506774525796, 3895.590962948892, 3976.296118639324, 4204.138151678899, 4483.417155769762, 4402.559744025604, 4436.889077773066, 4445.533219012005, 4447.533009034053, 4458.0, 3528.029791807248, 3757.924841503182, 3868.883933482, 4360.694751367406, 4392.868213953587, 3368.528406023159, 3509.4560343146854, 3587.641235876418, 3647.361711700442, 3839.022903204852, 4587.495375508692, 4642.932125611128, 4780.713157210578, 5110.616703747197, 5114.2584325273, 4596.701214421052, 5047.939932614156, 5073.224371469993, 5080.339555857752, 5143.614228932845, 5412.810551630674, 5625.38120806711, 5673.099511663598, 5701.600887937846, 5745.425457454221, 4138.448081969262, 4419.955800441987, 4504.076664283847, 4521.299198624186, 4583.152116858359, 5686.891056244075, 5794.188813566104, 5907.054871220627, 5954.166416701665, 5982.188371465404, 4057.9797101014306, 4170.457840480779, 4353.867994321436, 4496.820127194876, 4701.6003639690325, 4183.079722461053, 4392.516823149451, 4595.151006376068, 4786.904261914742, 4787.928181077286, 4906.533879281534, 4925.827596034122, 5071.39143302806, 5085.830259040367, 5156.8968620627375, 4786.617070634341, 5208.749900023967, 5584.459071122629, 5591.217229587861, 5615.887682246333, 1406.4374250299961, 1450.2386247220084, 1597.8162511311116, 1602.6313947792073, 1630.8695304375858, 1108.0304733358312, 1151.9884801151882, 1186.608419221672, 1188.7087663522493, 1191.4638412714314, 4444.888877778017, 5257.63196576236, 5264.6314757966575, 5408.783648654088, 5433.945660543423, 5111.437741848394, 5145.485451454824, 5883.382244864313, 5968.641881487125, 5987.856375402948, 5047.520485553867, 5049.747512624427, 5327.375150579033, 5372.435894231127, 5444.584408053855, 2595.701494328139, 3053.8931137410086, 3060.648025477054, 3098.5127138972966, 3106.353632574739, 5038.949610503922, 5357.678539287655, 5646.802361917313, 5832.5381083471175, 6063.333033366326, 4659.464161621457, 5131.281620573122, 5895.64626122434, 5965.940340596539, 6002.339742628307, 4868.050730107666, 5036.445990940993, 5177.378714554202, 5892.40904955651, 6099.878002440015, 4833.685810422311, 5801.6519008859605, 5885.676287804212, 5905.881882362242, 5938.61399009063, 5181.404138524118, 5449.365190442772, 5694.772209111589, 5801.82594522165, 5833.79581714638, 4983.632774432116, 6159.059896132704, 6172.888880001643, 6225.315215326401, 6265.718042688181, 5010.275461407761, 5144.228365745168, 5145.089731803781, 5165.457626949116, 5235.476452354734, 5229.431170648866, 5246.743900122984, 5614.476601354042, 5930.581716621145, 5957.702114894325, 5285.973570132279, 5357.705326207096, 5587.9824894558105, 5719.771209151588, 5773.430823283598, 5314.847486767865, 5353.732313384393, 5705.914411283751, 5708.257926469532, 5745.482906538432, 4617.953820461752, 5643.407442218508, 5652.943470565242, 5670.79600025984, 5681.545476361798, 4598.954010459853, 5451.700156491431, 5533.391326953952, 5717.514011309086, 5769.9711501442225, 4938.358013458227, 5278.81524146653, 5343.679379237258, 5535.916961245665, 5704.572157088318], "value": 4316.437283280688, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.4173006888902, 716.4411758828115, 720.9387202087822, 761.3642608421968, 850.3749743938206, 15069.924650376746, 16176.0, 16178.83821161788, 16187.91906040471, 16238.594035149143, 14687.632809179775, 14717.779233311481, 14730.852691473083, 14830.629234269149, 14919.403223871032, 912.9041450647686, 920.5765347939956, 925.9074092590754, 942.1426501883287, 953.3374304858127, 949.6581230756922, 957.0, 959.9088086631759, 960.4189465373453, 962.100436092256, 985.6057576969234, 1000.999000999001, 1004.507791182319, 1005.0100650858934, 1006.3157053203819, 997.1175509673955, 998.3859926145404, 999.0309399882103, 1002.04304888831, 1002.6139936124608, 733.336330620789, 735.2610626320567, 736.3446532586003, 737.2038198745342, 737.6348707389876, 15890.126043067627, 16033.198340083065, 16068.919655401765, 16766.0, 17048.318067277294, 13955.0, 13961.441542338294, 14039.508617198451, 14182.574522764333, 14362.353694083791, 11736.471858766376, 11915.523379064827, 11919.523219071227, 11941.10441716866, 12005.09961752872, 3611.837467313977, 3654.8172591370326, 3696.8890933272046, 3727.9440808387894, 3837.6737977271687, 116894.0, 117800.41099794446, 121550.0, 123593.38203309017, 123910.0, 10494.370337779605, 10517.631882884063, 10839.83740243897, 10898.128149748001, 10960.506777195045, 22208.55582888333, 22338.441538961655, 22345.664815027787, 22386.328410147722, 22403.103875844783, 6887.55361374107, 7516.887246691304, 7603.65783539742, 7716.92283077162, 7725.266099720518, 4271.10306835568, 4349.782510874444, 4414.867553973386, 4465.888352791141, 4546.0, 3112.8910488132806, 3405.4721518164724, 3473.0796338970194, 3475.94786078209, 3527.4356103023392, 516.8604476791245, 613.3835495327256, 620.4973971083347, 623.557274335218, 636.5289685632598, 664.3588936676034, 668.1481111582674, 695.488815720445, 705.1291654806311, 729.3253740290211, 8634.30925525969, 10407.895921040845, 10420.332746760481, 10497.685069447776, 10568.668459316894, 691.6852831961445, 694.2224708326706, 704.1233664088243, 707.1443553300461, 710.1478226128563, 4657.5575320344515, 5051.166557517969, 5057.570106540985, 5065.645404821701, 5068.847934561969, 78945.81581276286, 82469.58765206256, 91793.54103229441, 93632.0, 93837.06162938284, 57605.71197143987, 58519.70740146272, 67705.48659902385, 90727.09272907318, 93615.0, 74697.0, 75327.62336188393, 77511.0, 78229.60885195651, 78333.0, 10919.890801091888, 11174.888251117385, 11185.105191584817, 11479.885201148216, 11519.424028798694, 46052.0, 47144.29283560818, 48068.759656201495, 49661.75169124131, 51651.0, 64840.675796620715, 68124.0, 68685.31314686789, 69053.65473172803, 71647.64176179086, 10788.568457261621, 12450.190737602026, 12715.55495557651, 13028.609141725763, 13683.86316136826, 14414.279286035866, 15166.393344266105, 15406.922965385102, 15448.768268476206, 15576.221188940734, 12963.870361296265, 13701.45194192221, 13758.449662013407, 14026.859731402556, 14084.295785210903, 13052.54316098932, 13442.798358024833, 13445.798313025116, 13469.259190744211, 13562.932185339405, 12817.423215955117, 13599.456021759019, 13673.453061877812, 13695.246761428218, 13708.0, 11003.394813285346, 11364.374959377314, 11455.140864435034, 11557.537698491966, 11718.179727419, 10509.684709458728, 10646.883505241953, 11353.82928895298, 11491.367974761133, 11712.765744685228], "value": 7644.812004125992, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1571.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 119.0, 121.0, 121.0, 120.0, 121.0, 120.0, 114.0, 120.0, 119.0], "value": 118.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 115.0, 111.0, 145.0, 114.0, 113.0, 110.0, 115.0, 129.0, 116.0], "value": 120.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 200.0, 196.0, 196.0, 200.0, 196.0, 194.0, 199.0, 201.0, 196.0], "value": 197.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 60.0, 61.0, 87.0, 63.0, 58.0, 60.0, 60.0, 71.0, 61.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 87.0, 73.0, 72.0, 71.0, 81.0, 70.0, 62.0, 79.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 97.0, 88.0, 102.0, 87.0, 135.0, 93.0, 137.0, 92.0], "value": 101.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 110.0, 155.0, 134.0, 96.0, 98.0, 98.0, 98.0, 99.0, 97.0], "value": 110.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 87.0, 139.0, 150.0, 93.0, 117.0, 140.0, 102.0, 139.0, 142.0], "value": 125.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 68.0, 69.0, 70.0, 70.0, 70.0, 69.0, 70.0, 70.0], "value": 68.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 82.0, 88.0, 98.0, 65.0, 70.0, 56.0, 58.0, 58.0, 60.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 106.0, 83.0, 87.0, 87.0, 83.0, 84.0, 83.0, 86.0, 86.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 151.0, 151.0, 146.0, 163.0, 149.0, 141.0, 157.0, 209.0, 176.0], "value": 159.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 226.0, 151.0, 223.0, 234.0, 219.0, 168.0, 164.0, 162.0, 226.0], "value": 196.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 70.0, 79.0, 82.0, 86.0, 89.0, 90.0, 65.0, 67.0], "value": 76.1, "unit": "score"}]}]} 02:37:13 INFO - Return code: 0 02:37:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:37:13 ERROR - # TBPL SUCCESS # 02:37:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:37:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:37:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:37:13 INFO - 2017-03-20 02:37:13.970 screenresolution[1660:10669] starting screenresolution argv=screenresolution get 02:37:13 INFO - 2017-03-20 02:37:13.972 screenresolution[1660:10669] Display 0: 1600x1200x32@60 02:37:13 INFO - 2017-03-20 02:37:13.978 screenresolution[1661:10672] starting screenresolution argv=screenresolution list 02:37:13 INFO - Available Modes on Display 0 02:37:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:37:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:37:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:37:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:37:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:37:14 INFO - Intel Iris: 02:37:14 INFO - Chipset Model: Intel Iris 02:37:14 INFO - Type: GPU 02:37:14 INFO - Bus: Built-In 02:37:14 INFO - VRAM (Dynamic, Max): 1536 MB 02:37:14 INFO - Vendor: Intel (0x8086) 02:37:14 INFO - Device ID: 0x0a2e 02:37:14 INFO - Revision ID: 0x0009 02:37:14 INFO - Displays: 02:37:14 INFO - Display: 02:37:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:37:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:37:14 INFO - Display Serial Number: 1600x1200 60 02:37:14 INFO - Main Display: Yes 02:37:14 INFO - Mirror: Off 02:37:14 INFO - Online: Yes 02:37:14 INFO - Rotation: Supported 02:37:14 INFO - Return code: 0 02:37:14 INFO - Running post-action listener: _resource_record_post_action 02:37:14 INFO - [mozharness: 2017-03-20 09:37:14.212929Z] Finished run-tests step (success) 02:37:14 INFO - Running post-run listener: _resource_record_post_run 02:37:14 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 39311360; Write bytes: 513927168; Read time: 247; Write time: 1622 02:37:14 INFO - TinderboxPrint: CPU usage
54.5% 02:37:14 INFO - TinderboxPrint: I/O read bytes / time
39,311,360 / 247 02:37:14 INFO - TinderboxPrint: I/O write bytes / time
513,927,168 / 1,622 02:37:14 INFO - TinderboxPrint: CPU idle
2,187.1 (45.5%) 02:37:14 INFO - TinderboxPrint: CPU system
963.6 (20.1%) 02:37:14 INFO - TinderboxPrint: CPU user
1,651.0 (34.4%) 02:37:14 INFO - TinderboxPrint: Swap in / out
248,152,064 / 0 02:37:14 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202031616; Write bytes: 202225664; Read time: 15541; Write time: 424 02:37:14 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 37480448; Write bytes: 300945408; Read time: 232; Write time: 1167 02:37:14 INFO - Running post-run listener: _upload_blobber_files 02:37:14 INFO - Blob upload gear active. 02:37:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:37: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 02:37: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'] 02:37: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 02:37:15 INFO - (blobuploader) - INFO - Open directory for files ... 02:37:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:37:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:37:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:37:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:37:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:37:15 INFO - (blobuploader) - INFO - Done attempting. 02:37:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:37:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:37:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:37:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:37:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:37:16 INFO - (blobuploader) - INFO - Done attempting. 02:37:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:37:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:37:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:37:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:37:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:37:17 INFO - (blobuploader) - INFO - Done attempting. 02:37:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:37:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:37:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:37:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:37:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:37:20 INFO - (blobuploader) - INFO - Done attempting. 02:37:20 INFO - (blobuploader) - INFO - Iteration through files over. 02:37:20 INFO - Return code: 0 02:37:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:37:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:37:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9f15b249e8722b0bb147978ba13917b4451c56aa90f98491a6dc197b18650b2bbd685d5714a02ea2be816fc5377a088b566200d0426f257240cbe35c7b70ef1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/77ac26be105cb5ae98df2343c0405171077473cb99b61790c51b2cb82156bf96e15e829fe06cb3c933d99fbdb0c5aa408aeeb69bce73989c06894b31cea32352", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8ad6078832e3942c1f726c58ae6755dbb56a1e1725355756d41c72eb32e4b7d65fb9e10a1eb4b868a01f29d1d3ffb536c240b5a3833e03495abb80da435a177", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f852d61ce53cce982cbdda8a115b1a56640390ab65cd881c3065a26182b93c541b20ce85e0210df399691e72f3e5636b221ddaef927fa6dc1f3181c57d2b7035"} 02:37:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:37:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:37:20 INFO - Contents: 02:37:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9f15b249e8722b0bb147978ba13917b4451c56aa90f98491a6dc197b18650b2bbd685d5714a02ea2be816fc5377a088b566200d0426f257240cbe35c7b70ef1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/77ac26be105cb5ae98df2343c0405171077473cb99b61790c51b2cb82156bf96e15e829fe06cb3c933d99fbdb0c5aa408aeeb69bce73989c06894b31cea32352", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8ad6078832e3942c1f726c58ae6755dbb56a1e1725355756d41c72eb32e4b7d65fb9e10a1eb4b868a01f29d1d3ffb536c240b5a3833e03495abb80da435a177", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f852d61ce53cce982cbdda8a115b1a56640390ab65cd881c3065a26182b93c541b20ce85e0210df399691e72f3e5636b221ddaef927fa6dc1f3181c57d2b7035"} 02:37:20 INFO - Running post-run listener: copy_logs_to_upload_dir 02:37:20 INFO - Copying logs to upload dir... 02:37:20 INFO - mkdir: /builds/slave/test/build/upload/logs 02:37:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.412644 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-20 02:37:20.102259) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 02:37:20.108143) ========= 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.boadiEIQin/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.mY5p405KtE/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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/c9f15b249e8722b0bb147978ba13917b4451c56aa90f98491a6dc197b18650b2bbd685d5714a02ea2be816fc5377a088b566200d0426f257240cbe35c7b70ef1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/77ac26be105cb5ae98df2343c0405171077473cb99b61790c51b2cb82156bf96e15e829fe06cb3c933d99fbdb0c5aa408aeeb69bce73989c06894b31cea32352", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8ad6078832e3942c1f726c58ae6755dbb56a1e1725355756d41c72eb32e4b7d65fb9e10a1eb4b868a01f29d1d3ffb536c240b5a3833e03495abb80da435a177", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f852d61ce53cce982cbdda8a115b1a56640390ab65cd881c3065a26182b93c541b20ce85e0210df399691e72f3e5636b221ddaef927fa6dc1f3181c57d2b7035"} build_url:https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011304 build_url: 'https://queue.taskcluster.net/v1/task/eWqZUJCqS4u-vbhzXYUO3w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9f15b249e8722b0bb147978ba13917b4451c56aa90f98491a6dc197b18650b2bbd685d5714a02ea2be816fc5377a088b566200d0426f257240cbe35c7b70ef1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/77ac26be105cb5ae98df2343c0405171077473cb99b61790c51b2cb82156bf96e15e829fe06cb3c933d99fbdb0c5aa408aeeb69bce73989c06894b31cea32352", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8ad6078832e3942c1f726c58ae6755dbb56a1e1725355756d41c72eb32e4b7d65fb9e10a1eb4b868a01f29d1d3ffb536c240b5a3833e03495abb80da435a177", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f852d61ce53cce982cbdda8a115b1a56640390ab65cd881c3065a26182b93c541b20ce85e0210df399691e72f3e5636b221ddaef927fa6dc1f3181c57d2b7035"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 02:37:20.142849) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 02:37:20.143249) ========= 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.boadiEIQin/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.mY5p405KtE/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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.005328 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 02:37:20.192216) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 02:37:20.192567) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 02:37:20.193004) ========= ========= Total master_lag: 0.19 =========