builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0012 starttime: 1479297316.6 results: success (0) buildid: 20161116004016 builduid: a51a5df0f27e484ba36831f52f68e9dd revision: f99c3a999e81a017ca3f6026b1e75fe4ec31a50a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 03:55:16.601598) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 03:55:16.601960) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 03:55:16.602233) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 03:55:16.635283) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 03:55:16.635579) ========= 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.r6b5go3L1P/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.jkNDyCvY9a/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-16 03:55:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.87M=0.001s 2016-11-16 03:55:17 (9.87 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.441881 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 03:55:17.100870) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 03:55:17.101159) ========= 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.r6b5go3L1P/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.jkNDyCvY9a/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.027195 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 03:55:17.150258) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-16 03:55:17.150636) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f99c3a999e81a017ca3f6026b1e75fe4ec31a50a --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 f99c3a999e81a017ca3f6026b1e75fe4ec31a50a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.r6b5go3L1P/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.jkNDyCvY9a/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-16 03:55:17,245 truncating revision to first 12 chars 2016-11-16 03:55:17,245 Setting DEBUG logging. 2016-11-16 03:55:17,245 attempt 1/10 2016-11-16 03:55:17,245 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f99c3a999e81?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-16 03:55:18,459 unpacking tar archive at: mozilla-aurora-f99c3a999e81/testing/mozharness/ program finished with exit code 0 elapsedTime=1.812981 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-16 03:55:18.985925) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 03:55:18.986239) ========= script_repo_revision: f99c3a999e81a017ca3f6026b1e75fe4ec31a50a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 03:55:18.986650) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 03:55:18.986921) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 03:55:19.008228) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-16 03:55:19.008503) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.r6b5go3L1P/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.jkNDyCvY9a/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:55:19 INFO - MultiFileLogger online at 20161116 03:55:19 in /builds/slave/test 03:55:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:55:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:55:19 INFO - {'append_to_log': False, 03:55:19 INFO - 'base_work_dir': '/builds/slave/test', 03:55:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:55:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:55:19 INFO - 'branch': 'Mozilla-Aurora', 03:55:19 INFO - 'buildbot_json_path': 'buildprops.json', 03:55:19 INFO - 'config_files': ('talos/mac_config.py',), 03:55:19 INFO - 'default_actions': ('clobber', 03:55:19 INFO - 'read-buildbot-config', 03:55:19 INFO - 'download-and-extract', 03:55:19 INFO - 'populate-webroot', 03:55:19 INFO - 'create-virtualenv', 03:55:19 INFO - 'install', 03:55:19 INFO - 'run-tests'), 03:55:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:55:19 INFO - 'download_minidump_stackwalk': True, 03:55:19 INFO - 'download_symbols': 'ondemand', 03:55:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:55:19 INFO - 'tooltool.py': '/tools/tooltool.py', 03:55:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:55:19 INFO - '/tools/misc-python/virtualenv.py')}, 03:55:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:55:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:55:19 INFO - 'installer_path': 'installer.exe', 03:55:19 INFO - 'log_level': 'info', 03:55:19 INFO - 'log_name': 'talos', 03:55:19 INFO - 'log_to_console': True, 03:55:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:55:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:55:19 INFO - 'opt_config_files': (), 03:55:19 INFO - 'pip_index': False, 03:55:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:55:19 INFO - 'cmd': ('bash', 03:55:19 INFO - '-c', 03:55:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:55:19 INFO - 'enabled': True, 03:55:19 INFO - 'halt_on_failure': False, 03:55:19 INFO - 'name': 'check_screen_resolution'},), 03:55:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:55:19 INFO - 'cmd': ('bash', 03:55:19 INFO - '-c', 03:55:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:55:19 INFO - 'enabled': True, 03:55:19 INFO - 'halt_on_failure': False, 03:55:19 INFO - 'name': 'check_screen_resolution'},), 03:55:19 INFO - 'run_cmd_checks_enabled': True, 03:55:19 INFO - 'sps_profile': False, 03:55:19 INFO - 'sps_profile_interval': 0, 03:55:19 INFO - 'suite': 'dromaeojs', 03:55:19 INFO - 'system_bits': '32', 03:55:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:55:19 INFO - 'title': 't-yosemite-r7-0012', 03:55:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:55:19 INFO - 'use_talos_json': True, 03:55:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:55:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:55:19 INFO - 'work_dir': 'build'} 03:55:19 INFO - [mozharness: 2016-11-16 11:55:19.216665Z] Running clobber step. 03:55:19 INFO - Running pre-action listener: _resource_record_pre_action 03:55:19 INFO - Running main action method: clobber 03:55:19 INFO - rmtree: /builds/slave/test/build 03:55:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:55:21 INFO - Running post-action listener: _resource_record_post_action 03:55:21 INFO - [mozharness: 2016-11-16 11:55:21.589421Z] Finished clobber step (success) 03:55:21 INFO - [mozharness: 2016-11-16 11:55:21.589547Z] Running read-buildbot-config step. 03:55:21 INFO - Running pre-action listener: _resource_record_pre_action 03:55:21 INFO - Running main action method: read_buildbot_config 03:55:21 INFO - Using buildbot properties: 03:55:21 INFO - { 03:55:21 INFO - "project": "", 03:55:21 INFO - "product": "firefox", 03:55:21 INFO - "script_repo_revision": "production", 03:55:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:55:21 INFO - "repository": "", 03:55:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:55:21 INFO - "buildid": "20161116004016", 03:55:21 INFO - "stage_platform": "macosx64", 03:55:21 INFO - "basedir": "/builds/slave/test", 03:55:21 INFO - "nightly_build": "True", 03:55:21 INFO - "buildnumber": 39, 03:55:21 INFO - "slavename": "t-yosemite-r7-0012", 03:55:21 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 03:55:21 INFO - "platform": "yosemite_r7", 03:55:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:55:21 INFO - "branch": "mozilla-aurora", 03:55:21 INFO - "repo_path": "releases/mozilla-aurora", 03:55:21 INFO - "revision": "f99c3a999e81a017ca3f6026b1e75fe4ec31a50a", 03:55:21 INFO - "pgo_build": "False", 03:55:21 INFO - "builduid": "a51a5df0f27e484ba36831f52f68e9dd", 03:55:21 INFO - "slavebuilddir": "test" 03:55:21 INFO - } 03:55:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:55:21 INFO - Running post-action listener: _resource_record_post_action 03:55:21 INFO - [mozharness: 2016-11-16 11:55:21.592949Z] Finished read-buildbot-config step (success) 03:55:21 INFO - [mozharness: 2016-11-16 11:55:21.593086Z] Running download-and-extract step. 03:55:21 INFO - Running pre-action listener: _resource_record_pre_action 03:55:21 INFO - Running main action method: download_and_extract 03:55:21 INFO - mkdir: /builds/slave/test/build/tests 03:55:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:21 INFO - trying https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:55:21 INFO - Downloading https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:55:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 03:55:22 INFO - Downloaded 1199 bytes. 03:55:22 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:55:22 INFO - Using the following test package requirements: 03:55:22 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:55:22 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:55:22 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:55:22 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:55:22 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:55:22 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:55:22 INFO - u'jsshell-mac.zip'], 03:55:22 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:55:22 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:55:22 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:55:22 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:55:22 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:55:22 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:55:22 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:55:22 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:55:22 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:55:22 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:55:22 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:55:22 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:55:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:55:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:55:22 INFO - Fetch https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:55:22 INFO - Http code: 200 03:55:22 INFO - via: 1.1 9767e262e4af15c8d83a726bb0f44fa5.cloudfront.net (CloudFront) 03:55:22 INFO - x-amz-cf-id: pzYTseIwnvjZpI0t-nCgbKqsyEeLTxg5fUwyibrf1MBoxtg477szkQ== 03:55:22 INFO - x-amz-version-id: 2.wI_7z8QJrbaJjeOTHVLgB52NZ9zXv7 03:55:23 INFO - Expected file size: 16327573 03:55:23 INFO - Obtained file size: 16327573 03:55:30 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:55:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:55:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:55:30 INFO - Fetch https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:55:30 INFO - Http code: 200 03:55:30 INFO - via: 1.1 9767e262e4af15c8d83a726bb0f44fa5.cloudfront.net (CloudFront) 03:55:30 INFO - x-amz-cf-id: KCAnEKJIRE-EydeXXZUTdLx5RrohLopl2ngEM1NcDRDbomR7ZU1AoA== 03:55:30 INFO - x-amz-version-id: 2.wI_7z8QJrbaJjeOTHVLgB52NZ9zXv7 03:55:31 INFO - Expected file size: 16327573 03:55:31 INFO - Obtained file size: 16327573 03:55:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:55:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:55:37 INFO - Fetch https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:55:38 INFO - Http code: 200 03:55:38 INFO - via: 1.1 6e65abb04cb818a6ec78111935b507f7.cloudfront.net (CloudFront) 03:55:38 INFO - x-amz-cf-id: lUIklPKRouTyPPJ3UYs8ODIAm2ZEk9WTem8SiwYXXMGg7dWHk3a4EQ== 03:55:38 INFO - x-amz-version-id: .6wrN96IRZc7U6e67P0G3B3buTJyJITQ 03:55:38 INFO - Expected file size: 14409038 03:55:38 INFO - Obtained file size: 14409038 03:55:38 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')]} 03:55:38 INFO - trying https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:55:38 INFO - Downloading https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:55:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 03:55:41 INFO - Downloaded 114587142 bytes. 03:55:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:55:41 INFO - mkdir: /builds/slave/test/properties 03:55:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:55:41 INFO - Writing to file /builds/slave/test/properties/build_url 03:55:41 INFO - Contents: 03:55:41 INFO - build_url:https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:55:42 INFO - Running post-action listener: _resource_record_post_action 03:55:42 INFO - Running post-action listener: set_extra_try_arguments 03:55:42 INFO - [mozharness: 2016-11-16 11:55:42.283010Z] Finished download-and-extract step (success) 03:55:42 INFO - [mozharness: 2016-11-16 11:55:42.283164Z] Running populate-webroot step. 03:55:42 INFO - Running pre-action listener: _resource_record_pre_action 03:55:42 INFO - Running main action method: populate_webroot 03:55:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:55:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:55:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:55:42 INFO - '--tppagecycles', 03:55:42 INFO - '7'], 03:55:42 INFO - 'tests': ['tsvgm']}}, 03:55:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:55:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:55:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:55:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:55:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:55:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:55:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:42 INFO - 'talos_options': ['--disable-e10s'], 03:55:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:55:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:55:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:42 INFO - 'talos_options': ['--disable-e10s'], 03:55:42 INFO - 'tests': ['damp', 'tps']}, 03:55:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:42 INFO - 'tests': ['damp', 'tps']}, 03:55:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:55:42 INFO - 'tests': ['dromaeo_dom']}, 03:55:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:55:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:55:42 INFO - 'tests': ['basic_compositor_video']}, 03:55:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:55:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:55:42 INFO - 'tests': ['a11yr', 03:55:42 INFO - 'ts_paint', 03:55:42 INFO - 'tpaint', 03:55:42 INFO - 'sessionrestore', 03:55:42 INFO - 'sessionrestore_no_auto_restore', 03:55:42 INFO - 'tabpaint']}, 03:55:42 INFO - 'other-e10s': {'tests': ['a11yr', 03:55:42 INFO - 'ts_paint', 03:55:42 INFO - 'tpaint', 03:55:42 INFO - 'sessionrestore', 03:55:42 INFO - 'sessionrestore_no_auto_restore', 03:55:42 INFO - 'tabpaint']}, 03:55:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:55:42 INFO - 'ts_paint', 03:55:42 INFO - 'tpaint', 03:55:42 INFO - 'sessionrestore', 03:55:42 INFO - 'sessionrestore_no_auto_restore', 03:55:42 INFO - 'tabpaint']}, 03:55:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:55:42 INFO - 'ts_paint', 03:55:42 INFO - 'tpaint', 03:55:42 INFO - 'sessionrestore', 03:55:42 INFO - 'sessionrestore_no_auto_restore', 03:55:42 INFO - 'tabpaint']}, 03:55:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:55:42 INFO - 'tests': ['a11yr', 03:55:42 INFO - 'ts_paint', 03:55:42 INFO - 'tpaint', 03:55:42 INFO - 'sessionrestore', 03:55:42 INFO - 'sessionrestore_no_auto_restore', 03:55:42 INFO - 'tabpaint']}, 03:55:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:55:42 INFO - 'tests': ['a11yr', 03:55:42 INFO - 'ts_paint', 03:55:42 INFO - 'tpaint', 03:55:42 INFO - 'sessionrestore', 03:55:42 INFO - 'sessionrestore_no_auto_restore', 03:55:42 INFO - 'tabpaint']}, 03:55:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:55:42 INFO - 'tests': ['tsvgx', 03:55:42 INFO - 'tsvgr_opacity', 03:55:42 INFO - 'tart', 03:55:42 INFO - 'tscrollx', 03:55:42 INFO - 'cart']}, 03:55:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:55:42 INFO - 'tsvgr_opacity', 03:55:42 INFO - 'tart', 03:55:42 INFO - 'tscrollx', 03:55:42 INFO - 'cart']}, 03:55:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:42 INFO - 'talos_options': ['--disable-e10s'], 03:55:42 INFO - 'tests': ['tp5o']}, 03:55:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:42 INFO - 'tests': ['tp5o']}, 03:55:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:42 INFO - 'talos_options': ['--disable-e10s', 03:55:42 INFO - '--xperf_path', 03:55:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:55:42 INFO - 'tests': ['tp5n']}, 03:55:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:42 INFO - 'talos_options': ['--xperf_path', 03:55:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:55:42 INFO - 'tests': ['tp5n']}}, 03:55:42 INFO - 'talos.zip': {'path': '', 03:55:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:55:42 INFO - Running post-action listener: _resource_record_post_action 03:55:42 INFO - [mozharness: 2016-11-16 11:55:42.302127Z] Finished populate-webroot step (success) 03:55:42 INFO - [mozharness: 2016-11-16 11:55:42.302234Z] Running create-virtualenv step. 03:55:42 INFO - Running pre-action listener: _resource_record_pre_action 03:55:42 INFO - Running main action method: create_virtualenv 03:55:42 INFO - Creating virtualenv /builds/slave/test/build/venv 03:55:42 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 03:55:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:55:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:55:42 INFO - Using real prefix '/tools/python27' 03:55:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:55:43 INFO - Installing distribute.............................................................................................................................................................................................done. 03:55:45 INFO - Installing pip.................done. 03:55:45 INFO - Return code: 0 03:55:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:55:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:55:46 INFO - Reading from file tmpfile_stdout 03:55:46 INFO - Output received: 03:55:46 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) 03:55:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:55:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:46 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 0x105e53490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dc9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb9d61530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c67d30>, '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 0x105acddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c5fb20>, '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.r6b5go3L1P/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.jkNDyCvY9a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:55:46 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 03:55:46 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 03:55:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r6b5go3L1P/Render', 03:55:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:46 INFO - 'HOME': '/Users/cltbld', 03:55:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:46 INFO - 'LOGNAME': 'cltbld', 03:55:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:46 INFO - 'MOZ_NO_REMOTE': '1', 03:55:46 INFO - 'NO_EM_RESTART': '1', 03:55:46 INFO - 'PAGER': '/bin/cat', 03:55:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:46 INFO - 'PWD': '/builds/slave/test', 03:55:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:46 INFO - 'SHELL': '/bin/bash', 03:55:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkNDyCvY9a/Listeners', 03:55:46 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:55:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:46 INFO - 'USER': 'cltbld', 03:55:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:46 INFO - 'XPC_FLAGS': '0x0', 03:55:46 INFO - 'XPC_SERVICE_NAME': '0', 03:55:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:46 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 03:55:46 INFO - Cleaning up... 03:55:46 INFO - Return code: 0 03:55:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:55:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:46 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 0x105e53490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dc9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb9d61530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c67d30>, '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 0x105acddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c5fb20>, '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.r6b5go3L1P/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.jkNDyCvY9a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:55:46 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 03:55:46 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 03:55:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r6b5go3L1P/Render', 03:55:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:46 INFO - 'HOME': '/Users/cltbld', 03:55:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:46 INFO - 'LOGNAME': 'cltbld', 03:55:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:46 INFO - 'MOZ_NO_REMOTE': '1', 03:55:46 INFO - 'NO_EM_RESTART': '1', 03:55:46 INFO - 'PAGER': '/bin/cat', 03:55:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:46 INFO - 'PWD': '/builds/slave/test', 03:55:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:46 INFO - 'SHELL': '/bin/bash', 03:55:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkNDyCvY9a/Listeners', 03:55:46 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:55:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:46 INFO - 'USER': 'cltbld', 03:55:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:46 INFO - 'XPC_FLAGS': '0x0', 03:55:46 INFO - 'XPC_SERVICE_NAME': '0', 03:55:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:46 INFO - Downloading/unpacking psutil>=3.1.1 03:55:46 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 03:55:46 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 03:55:46 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 03:55:46 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 03:55:46 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 03:55:46 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 03:55:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:55:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:55:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:55:50 INFO - Installing collected packages: psutil 03:55:50 INFO - Running setup.py install for psutil 03:55:50 INFO - building 'psutil._psutil_osx' extension 03:55:50 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 03:55:51 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 03:55:51 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 03:55:51 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 03:55:51 INFO - building 'psutil._psutil_posix' extension 03:55:51 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 03:55:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:55:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:55:51 INFO - ^ 03:55:51 INFO - 1 warning generated. 03:55:51 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 03:55:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:55:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:55:51 INFO - Successfully installed psutil 03:55:51 INFO - Cleaning up... 03:55:51 INFO - Return code: 0 03:55:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:55:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:51 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 0x105e53490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dc9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb9d61530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c67d30>, '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 0x105acddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c5fb20>, '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.r6b5go3L1P/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.jkNDyCvY9a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:55:51 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 03:55:51 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 03:55:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r6b5go3L1P/Render', 03:55:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:51 INFO - 'HOME': '/Users/cltbld', 03:55:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:51 INFO - 'LOGNAME': 'cltbld', 03:55:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:51 INFO - 'MOZ_NO_REMOTE': '1', 03:55:51 INFO - 'NO_EM_RESTART': '1', 03:55:51 INFO - 'PAGER': '/bin/cat', 03:55:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:51 INFO - 'PWD': '/builds/slave/test', 03:55:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:51 INFO - 'SHELL': '/bin/bash', 03:55:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkNDyCvY9a/Listeners', 03:55:51 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:55:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:51 INFO - 'USER': 'cltbld', 03:55:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:51 INFO - 'XPC_FLAGS': '0x0', 03:55:51 INFO - 'XPC_SERVICE_NAME': '0', 03:55:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:55:52 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 03:55:52 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 03:55:52 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 03:55:52 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 03:55:52 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 03:55:52 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 03:55:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:55:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:55:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:55:54 INFO - Installing collected packages: mozsystemmonitor 03:55:54 INFO - Running setup.py install for mozsystemmonitor 03:55:54 INFO - Successfully installed mozsystemmonitor 03:55:54 INFO - Cleaning up... 03:55:54 INFO - Return code: 0 03:55:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:55:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:54 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 0x105e53490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dc9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb9d61530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c67d30>, '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 0x105acddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c5fb20>, '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.r6b5go3L1P/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.jkNDyCvY9a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:55:54 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 03:55:54 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 03:55:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r6b5go3L1P/Render', 03:55:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:54 INFO - 'HOME': '/Users/cltbld', 03:55:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:54 INFO - 'LOGNAME': 'cltbld', 03:55:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:54 INFO - 'MOZ_NO_REMOTE': '1', 03:55:54 INFO - 'NO_EM_RESTART': '1', 03:55:54 INFO - 'PAGER': '/bin/cat', 03:55:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:54 INFO - 'PWD': '/builds/slave/test', 03:55:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:54 INFO - 'SHELL': '/bin/bash', 03:55:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkNDyCvY9a/Listeners', 03:55:54 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:55:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:54 INFO - 'USER': 'cltbld', 03:55:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:54 INFO - 'XPC_FLAGS': '0x0', 03:55:54 INFO - 'XPC_SERVICE_NAME': '0', 03:55:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:55 INFO - Downloading/unpacking jsonschema==2.5.1 03:55:55 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 03:55:55 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 03:55:55 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 03:55:55 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 03:55:55 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 03:55:55 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 03:55:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:55:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:55: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 03:55: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 03:55: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 03:55: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 03:55: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 03:55: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 03:55:58 INFO - Downloading functools32-3.2.3-2.tar.gz 03:55:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:55:58 INFO - warning: no files found matching '*.txt' 03:55:58 INFO - no previously-included directories found matching 'build' 03:55:58 INFO - no previously-included directories found matching 'dist' 03:55:58 INFO - no previously-included directories found matching '.git*' 03:55:58 INFO - Installing collected packages: jsonschema, functools32 03:55:58 INFO - Running setup.py install for functools32 03:55:58 INFO - warning: no files found matching '*.txt' 03:55:58 INFO - no previously-included directories found matching 'build' 03:55:58 INFO - no previously-included directories found matching 'dist' 03:55:58 INFO - no previously-included directories found matching '.git*' 03:55:58 INFO - Successfully installed jsonschema functools32 03:55:58 INFO - Cleaning up... 03:55:58 INFO - Return code: 0 03:55:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:55:58 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')]} 03:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:58 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')]} 03:55:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:58 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 0x105e53490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dc9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb9d61530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c67d30>, '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 0x105acddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c5fb20>, '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.r6b5go3L1P/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.jkNDyCvY9a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:55:58 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 03:55:58 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 03:55:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r6b5go3L1P/Render', 03:55:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:58 INFO - 'HOME': '/Users/cltbld', 03:55:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:58 INFO - 'LOGNAME': 'cltbld', 03:55:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:58 INFO - 'MOZ_NO_REMOTE': '1', 03:55:58 INFO - 'NO_EM_RESTART': '1', 03:55:58 INFO - 'PAGER': '/bin/cat', 03:55:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:58 INFO - 'PWD': '/builds/slave/test', 03:55:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:58 INFO - 'SHELL': '/bin/bash', 03:55:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkNDyCvY9a/Listeners', 03:55:58 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:55:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:58 INFO - 'USER': 'cltbld', 03:55:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:58 INFO - 'XPC_FLAGS': '0x0', 03:55:58 INFO - 'XPC_SERVICE_NAME': '0', 03:55:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:55:58 INFO - Cleaning up... 03:55:58 INFO - Return code: 0 03:55:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:55:58 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')]} 03:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:58 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')]} 03:55:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:58 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 0x105e53490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dc9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb9d61530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c67d30>, '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 0x105acddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c5fb20>, '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.r6b5go3L1P/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.jkNDyCvY9a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:55:58 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 03:55:58 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 03:55:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r6b5go3L1P/Render', 03:55:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:58 INFO - 'HOME': '/Users/cltbld', 03:55:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:58 INFO - 'LOGNAME': 'cltbld', 03:55:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:58 INFO - 'MOZ_NO_REMOTE': '1', 03:55:58 INFO - 'NO_EM_RESTART': '1', 03:55:58 INFO - 'PAGER': '/bin/cat', 03:55:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:58 INFO - 'PWD': '/builds/slave/test', 03:55:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:58 INFO - 'SHELL': '/bin/bash', 03:55:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkNDyCvY9a/Listeners', 03:55:58 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:55:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:58 INFO - 'USER': 'cltbld', 03:55:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:58 INFO - 'XPC_FLAGS': '0x0', 03:55:58 INFO - 'XPC_SERVICE_NAME': '0', 03:55:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:59 INFO - Downloading/unpacking blobuploader==1.2.4 03:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:01 INFO - Downloading blobuploader-1.2.4.tar.gz 03:56:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:56:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:56:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:56:02 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 03:56:02 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 03:56:02 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 03:56:02 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 03:56:02 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 03:56:02 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 03:56:02 INFO - Downloading docopt-0.6.1.tar.gz 03:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:56:02 INFO - Installing collected packages: blobuploader, requests, docopt 03:56:02 INFO - Running setup.py install for blobuploader 03:56:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:56:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:56:03 INFO - Running setup.py install for requests 03:56:03 INFO - Running setup.py install for docopt 03:56:03 INFO - Successfully installed blobuploader requests docopt 03:56:03 INFO - Cleaning up... 03:56:03 INFO - Return code: 0 03:56:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:56:03 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')]} 03:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:03 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')]} 03:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:03 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 0x105e53490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dc9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb9d61530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c67d30>, '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 0x105acddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c5fb20>, '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.r6b5go3L1P/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.jkNDyCvY9a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:56:03 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 03:56:03 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 03:56:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r6b5go3L1P/Render', 03:56:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:03 INFO - 'HOME': '/Users/cltbld', 03:56:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:03 INFO - 'LOGNAME': 'cltbld', 03:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:03 INFO - 'MOZ_NO_REMOTE': '1', 03:56:03 INFO - 'NO_EM_RESTART': '1', 03:56:03 INFO - 'PAGER': '/bin/cat', 03:56:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:03 INFO - 'PWD': '/builds/slave/test', 03:56:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:03 INFO - 'SHELL': '/bin/bash', 03:56:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkNDyCvY9a/Listeners', 03:56:03 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:56:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:03 INFO - 'USER': 'cltbld', 03:56:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:03 INFO - 'XPC_FLAGS': '0x0', 03:56:03 INFO - 'XPC_SERVICE_NAME': '0', 03:56:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:56:04 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-aY7l2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:56:04 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-jmYryl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:56:04 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-wG9AHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:56:04 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-RgslSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:56:04 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-MSsSDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:56:04 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-eyf5ED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:56:04 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-SiZ0Bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:56:04 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-0DvT5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:56:04 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-wWS41G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:56:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-y44Atx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:56:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-rLvpj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:56:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-WDa7y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:56:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-4a_Y3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:56:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-jtlfac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-b4rYhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:56:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-qYn8Lb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:56:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-keKQ3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:56:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:56:05 INFO - Running setup.py install for manifestparser 03:56:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:56:06 INFO - Running setup.py install for mozcrash 03:56:06 INFO - Running setup.py install for mozdebug 03:56:06 INFO - Running setup.py install for mozdevice 03:56:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:56:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:56:06 INFO - Running setup.py install for mozfile 03:56:06 INFO - Running setup.py install for mozhttpd 03:56:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:56:06 INFO - Running setup.py install for mozinfo 03:56:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:56:06 INFO - Running setup.py install for mozInstall 03:56:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:56:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:56:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:56:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:56:07 INFO - Running setup.py install for mozleak 03:56:07 INFO - Running setup.py install for mozlog 03:56:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:56:07 INFO - Running setup.py install for moznetwork 03:56:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:56:07 INFO - Running setup.py install for mozprocess 03:56:07 INFO - Running setup.py install for mozprofile 03:56:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:56:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:56:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:56:07 INFO - Running setup.py install for mozrunner 03:56:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:56:08 INFO - Running setup.py install for mozscreenshot 03:56:08 INFO - Running setup.py install for moztest 03:56:08 INFO - Running setup.py install for mozversion 03:56:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:56:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:56:08 INFO - Cleaning up... 03:56:08 INFO - Return code: 0 03:56:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:56:08 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')]} 03:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:08 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')]} 03:56:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:08 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 0x105e53490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dc9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb9d61530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c67d30>, '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 0x105acddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c5fb20>, '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.r6b5go3L1P/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.jkNDyCvY9a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:56:08 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 03:56:08 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 03:56:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r6b5go3L1P/Render', 03:56:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:08 INFO - 'HOME': '/Users/cltbld', 03:56:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:08 INFO - 'LOGNAME': 'cltbld', 03:56:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:08 INFO - 'MOZ_NO_REMOTE': '1', 03:56:08 INFO - 'NO_EM_RESTART': '1', 03:56:08 INFO - 'PAGER': '/bin/cat', 03:56:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:08 INFO - 'PWD': '/builds/slave/test', 03:56:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:08 INFO - 'SHELL': '/bin/bash', 03:56:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkNDyCvY9a/Listeners', 03:56:08 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:56:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:08 INFO - 'USER': 'cltbld', 03:56:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:08 INFO - 'XPC_FLAGS': '0x0', 03:56:08 INFO - 'XPC_SERVICE_NAME': '0', 03:56:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:56:08 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-SL7WBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:56:08 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)) 03:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:56:08 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-0rDEan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:56:08 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)) 03:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:56:08 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-hYVtNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:56:09 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)) 03:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:56:09 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-3waNZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 03:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:56:09 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-KcUTrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:56:09 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)) 03:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:56:09 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-iHwR05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:56:09 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)) 03:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:56:09 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Y6QR8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:56:09 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)) 03:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:56:09 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-tezr81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:56:09 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)) 03:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:56:09 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-6eZqwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:56:09 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)) 03:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:56:09 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-ztxx4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:56:09 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)) 03:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:56:09 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-OK27Fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:56:09 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)) 03:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:56:09 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-OpjUEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:56:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-m1Kqkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:56:10 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)) 03:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:56:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-JXYg06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:56:10 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)) 03:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Mwrjyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:10 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)) 03:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:56:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-3frVid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:56:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-6LMoJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:56:10 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)) 03:56:10 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)) 03:56:10 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)) 03:56:10 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)) 03:56:10 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:56:10 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:56:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:56:10 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 03:56:10 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 03:56:10 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 03:56:10 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 03:56:10 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 03:56:10 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 03:56:13 INFO - Downloading blessings-1.6.tar.gz 03:56:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:56:13 INFO - Installing collected packages: blessings 03:56:13 INFO - Running setup.py install for blessings 03:56:13 INFO - Successfully installed blessings 03:56:13 INFO - Cleaning up... 03:56:13 INFO - Return code: 0 03:56:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:56:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:56:13 INFO - Reading from file tmpfile_stdout 03:56:13 INFO - Current package versions: 03:56:13 INFO - blessings == 1.6 03:56:13 INFO - blobuploader == 1.2.4 03:56:13 INFO - docopt == 0.6.1 03:56:13 INFO - functools32 == 3.2.3-2 03:56:13 INFO - jsonschema == 2.5.1 03:56:13 INFO - manifestparser == 1.1 03:56:13 INFO - mozInstall == 1.12 03:56:13 INFO - mozcrash == 1.0 03:56:13 INFO - mozdebug == 0.1 03:56:13 INFO - mozdevice == 0.48 03:56:13 INFO - mozfile == 1.2 03:56:13 INFO - mozhttpd == 0.7 03:56:13 INFO - mozinfo == 0.9 03:56:13 INFO - mozleak == 0.1 03:56:13 INFO - mozlog == 3.4 03:56:13 INFO - moznetwork == 0.27 03:56:13 INFO - mozprocess == 0.23 03:56:13 INFO - mozprofile == 0.28 03:56:13 INFO - mozrunner == 6.13 03:56:13 INFO - mozscreenshot == 0.1 03:56:13 INFO - mozsystemmonitor == 0.3 03:56:13 INFO - moztest == 0.7 03:56:13 INFO - mozversion == 1.4 03:56:13 INFO - psutil == 3.1.1 03:56:13 INFO - requests == 1.2.3 03:56:13 INFO - wsgiref == 0.1.2 03:56:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:13 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 0x105e53490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dc9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb9d61530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c67d30>, '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 0x105acddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c5fb20>, '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.r6b5go3L1P/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.jkNDyCvY9a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:56:13 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 03:56:13 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 03:56:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r6b5go3L1P/Render', 03:56:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:13 INFO - 'HOME': '/Users/cltbld', 03:56:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:13 INFO - 'LOGNAME': 'cltbld', 03:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:13 INFO - 'MOZ_NO_REMOTE': '1', 03:56:13 INFO - 'NO_EM_RESTART': '1', 03:56:13 INFO - 'PAGER': '/bin/cat', 03:56:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:13 INFO - 'PWD': '/builds/slave/test', 03:56:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:13 INFO - 'SHELL': '/bin/bash', 03:56:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkNDyCvY9a/Listeners', 03:56:13 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:56:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:13 INFO - 'USER': 'cltbld', 03:56:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:13 INFO - 'XPC_FLAGS': '0x0', 03:56:13 INFO - 'XPC_SERVICE_NAME': '0', 03:56:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:13 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)) 03:56:13 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)) 03:56:13 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)) 03:56:13 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)) 03:56:13 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)) 03:56:13 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)) 03:56:13 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)) 03:56:13 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)) 03:56:13 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)) 03:56:13 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)) 03:56:13 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)) 03:56:13 INFO - Cleaning up... 03:56:13 INFO - Return code: 0 03:56:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e53490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dc9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb9d61530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c67d30>, '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 0x105acddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c5fb20>, '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.r6b5go3L1P/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.jkNDyCvY9a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:56:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:56:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r6b5go3L1P/Render', 03:56:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:13 INFO - 'HOME': '/Users/cltbld', 03:56:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:13 INFO - 'LOGNAME': 'cltbld', 03:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:13 INFO - 'MOZ_NO_REMOTE': '1', 03:56:13 INFO - 'NO_EM_RESTART': '1', 03:56:13 INFO - 'PAGER': '/bin/cat', 03:56:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:13 INFO - 'PWD': '/builds/slave/test', 03:56:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:13 INFO - 'SHELL': '/bin/bash', 03:56:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkNDyCvY9a/Listeners', 03:56:13 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:56:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:13 INFO - 'USER': 'cltbld', 03:56:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:13 INFO - 'XPC_FLAGS': '0x0', 03:56:13 INFO - 'XPC_SERVICE_NAME': '0', 03:56:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:56:14 INFO - Cleaning up... 03:56:14 INFO - Return code: 0 03:56:14 INFO - Running post-action listener: _resource_record_post_action 03:56:14 INFO - Running post-action listener: _start_resource_monitoring 03:56:14 INFO - Starting resource monitoring. 03:56:14 INFO - [mozharness: 2016-11-16 11:56:14.251050Z] Finished create-virtualenv step (success) 03:56:14 INFO - [mozharness: 2016-11-16 11:56:14.252066Z] Running install step. 03:56:14 INFO - Running pre-action listener: _resource_record_pre_action 03:56:14 INFO - Running main action method: install 03:56:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:56:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:56:14 INFO - Reading from file tmpfile_stdout 03:56:14 INFO - Detecting whether we're running mozinstall >=1.0... 03:56:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:56:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:56:14 INFO - Reading from file tmpfile_stdout 03:56:14 INFO - Output received: 03:56:14 INFO - Usage: mozinstall [options] installer 03:56:14 INFO - Options: 03:56:14 INFO - -h, --help show this help message and exit 03:56:14 INFO - -d DEST, --destination=DEST 03:56:14 INFO - Directory to install application into. [default: 03:56:14 INFO - "/builds/slave/test"] 03:56:14 INFO - --app=APP Application being installed. [default: firefox] 03:56:14 INFO - mkdir: /builds/slave/test/build/application 03:56:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:56:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:56:46 INFO - Reading from file tmpfile_stdout 03:56:46 INFO - Output received: 03:56:46 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:56:46 INFO - Running post-action listener: _resource_record_post_action 03:56:46 INFO - [mozharness: 2016-11-16 11:56:46.332721Z] Finished install step (success) 03:56:46 INFO - [mozharness: 2016-11-16 11:56:46.332886Z] Running run-tests step. 03:56:46 INFO - Running pre-action listener: _resource_record_pre_action 03:56:46 INFO - Running main action method: run_tests 03:56:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:56:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:56:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:56:46 INFO - 2016-11-16 03:56:46.347 screenresolution[1633:8054] starting screenresolution argv=screenresolution get 03:56:46 INFO - 2016-11-16 03:56:46.349 screenresolution[1633:8054] Display 0: 1600x1200x32@60 03:56:46 INFO - 2016-11-16 03:56:46.355 screenresolution[1634:8056] starting screenresolution argv=screenresolution list 03:56:46 INFO - Available Modes on Display 0 03:56:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:56:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:56:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:56:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:56:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:56:46 INFO - Intel Iris: 03:56:46 INFO - Chipset Model: Intel Iris 03:56:46 INFO - Type: GPU 03:56:46 INFO - Bus: Built-In 03:56:46 INFO - VRAM (Dynamic, Max): 1536 MB 03:56:46 INFO - Vendor: Intel (0x8086) 03:56:46 INFO - Device ID: 0x0a2e 03:56:46 INFO - Revision ID: 0x0009 03:56:46 INFO - Displays: 03:56:46 INFO - Display: 03:56:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:56:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:56:46 INFO - Display Serial Number: 1600x1200 60 03:56:46 INFO - Main Display: Yes 03:56:46 INFO - Mirror: Off 03:56:46 INFO - Online: Yes 03:56:46 INFO - Rotation: Supported 03:56:46 INFO - Return code: 0 03:56:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:56:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:56:46 INFO - Python 2.7.3 03:56:46 INFO - Return code: 0 03:56:46 INFO - grabbing minidump binary from tooltool 03:56:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:46 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 0x105c67d30>, '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 0x105acddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c5fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:56:46 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 03:56:46 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 03:56:46 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 03:56:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:56:46 INFO - Return code: 0 03:56:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:56:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:56:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:56:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:56:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:56:46 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:56:46 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0012 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:56:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r6b5go3L1P/Render', 03:56:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:46 INFO - 'HOME': '/Users/cltbld', 03:56:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:46 INFO - 'LOGNAME': 'cltbld', 03:56:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:56:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:56:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:46 INFO - 'MOZ_NO_REMOTE': '1', 03:56:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:56:46 INFO - 'NO_EM_RESTART': '1', 03:56:46 INFO - 'PAGER': '/bin/cat', 03:56:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:46 INFO - 'PWD': '/builds/slave/test', 03:56:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:56:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:46 INFO - 'SHELL': '/bin/bash', 03:56:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkNDyCvY9a/Listeners', 03:56:46 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:56:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:46 INFO - 'USER': 'cltbld', 03:56:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:46 INFO - 'XPC_FLAGS': '0x0', 03:56:46 INFO - 'XPC_SERVICE_NAME': '0', 03:56:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:56:46 INFO - mozversion application_buildid: 20161116004016 03:56:46 INFO - mozversion application_changeset: f99c3a999e81a017ca3f6026b1e75fe4ec31a50a 03:56:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:56:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:56:46 INFO - mozversion application_name: Firefox 03:56:46 INFO - mozversion application_remotingname: firefox-dev 03:56:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:56:46 INFO - mozversion application_vendor: Mozilla 03:56:46 INFO - mozversion application_version: 52.0a2 03:56:46 INFO - mozversion platform_buildid: 20161116004016 03:56:46 INFO - mozversion platform_changeset: f99c3a999e81a017ca3f6026b1e75fe4ec31a50a 03:56:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:56:46 INFO - mozversion platform_version: 52.0a2 03:56:46 INFO - using testdate: 1479297406 03:56:46 INFO - actual date: 1479297406 03:56:46 INFO - starting webserver on 'localhost:49246' 03:56:46 INFO - SUITE-START | Running 2 tests 03:56:46 INFO - TEST-START | dromaeo_css 03:56:46 INFO - operating with platform_type : mac_ 03:56:46 INFO - Initialising browser for dromaeo_css test... 03:56:47 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpPLCdFP/profile http://localhost:49246/getInfo.html) 03:56:48 ERROR - PROCESS | 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 03:56:49 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 03:56:49 INFO - PROCESS | 1644 | colorDepth:24 03:56:49 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 03:56:49 INFO - PROCESS | 1644 | __metrics 03:56:49 INFO - PROCESS | 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:56:49 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:56:50 INFO - TEST-INFO | 1644: exit 0 03:56:50 INFO - Browser initialized. 03:56:50 INFO - Running cycle 1/1 for dromaeo_css test... 03:56:50 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpPLCdFP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:57:02 INFO - PROCESS | 1648 | RSS: Main: 218128384 03:57:02 INFO - PROCESS | 1648 | 03:57:54 INFO - PROCESS | 1648 | 1479297474171 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:00:06 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 04:00:09 INFO - PROCESS | 1648 | RSS: Main: 432156672 04:00:09 INFO - PROCESS | 1648 | 04:03:12 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 04:03:12 INFO - PROCESS | 1648 | RSS: Main: 349794304 04:03:12 INFO - PROCESS | 1648 | 04:06:15 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 04:06:15 INFO - PROCESS | 1648 | RSS: Main: 321515520 04:06:15 INFO - PROCESS | 1648 | 04:09:18 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 04:09:18 INFO - PROCESS | 1648 | RSS: Main: 322457600 04:09:18 INFO - PROCESS | 1648 | 04:12:22 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 04:12:22 INFO - PROCESS | 1648 | RSS: Main: 308137984 04:12:22 INFO - PROCESS | 1648 | 04:15:25 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 04:15:26 INFO - PROCESS | 1648 | RSS: Main: 429641728 04:15:26 INFO - PROCESS | 1648 | 04:15:26 INFO - PROCESS | 1648 | __start_tp_report 04:15:26 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 04:15:26 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 04:15:26 INFO - PROCESS | 1648 | |i|pagename|runs| 04:15:26 INFO - PROCESS | 1648 | |0;dojo.html;3347.2134048498597;3358.7984720916734;3360.831958402079;3369.9326013479745;3414.1123307939943;5723.828285151447;5758.683272420015;5810;5810.244668193134;5812.680302583356;5689.715514224283;5690.430956904307;5733.856653583663;5756.683382413966;5804.477597016267;5251.054810134175;5273.4462881397285;5301.257823904656;5313.22958171065;5327.813526526553;4305.182015417076;5236.3978142513715;5243.082460569404;5254.57963362932;5264.789408423659;5288.2596436498725;5328.49379308965;5336.30628018359;5339.8398048058425;5353.625246232766;5334.013207556595;5346.144616861322;5346.91979620306;5358.732063396834;5387.622866399353;5042.193249080166;5177.119889618773;5268.104422248189;5272.367315922075;5295.205719142121;5671.120976248688;5677.630953987978;5707.486326230659;5714.51426628737;5721.056025755745;6130.990686281824;6169.167162433059;6183.319834818167;6235.345288744662;6258.624482531017;5296.894062118775;6237.469815065725;6270.059491076329;6348.714307856158;6353.30113687494;5885.823425297205;5904.97047514764;5913.586048976616;5927.822165335004;5935.52515798735;6257.4681152102585;6261.279952805395;6274.090256912722;6328.177336946167;6329.8734025320155;6180.258368995659;6227.221597300338;6238.376162383725;6239.906401403982;6246;6299.496040316763;6329.715162817684;6335.841603959846;6345.238571371465;6351.333110023381;6136.447719705248;6145.187169784924;6159.692015399212;6165.013597824327;6259.937400625936;5641.238432811518;6040.6710523684715;6247.094171345129;6304.243490781136;6304.653244071528;4944.480829512921;4956.008798240367;4960.50394960501;4966.6771659842;4972.950270497321;4401.163778882003;4406.62543683784;4490.281554951257;4493.415855938707;4501.369808226851;5747.626404283792;5788.82633520995;5818.2436283282905;5900.4099590041515;5911.172435858984;6302.306746257908;6322.557420980487;6337.461315788118;6361.363863613602;6368.331325210786;5788.5369170466265;5810.012297909366;5844.1233814927255;5850.12248162779;5868.354481007086;5361.544268737201;5382.757775900094;5405.972970135203;5417.702026388586;5443.673379597237;6264.812055638338;6290.370962903673;6295.96852015737;6356.2054743157105;6356.491480681442;6332.208473940757;6345.4923606112325;6347.111404403295;6347.682615869096;6354.078658594385;6200.968995155175;6203.96898015507;6305.495560355253;6343.9365606343345;6358.300586935341;6140.718981675666;6146.877062458636;6150.569460137747;6202.689865506797;6270.278917924497;6308.369163083838;6313.2424109107205;6316.684165791784;6319.557630965788;6320.241571011508;6101.572889897665;6116.663583502772;6133.478654314434;6143.508519318533;6158.938410615837;5983.880322393615;6049.304330002106;6052.182955300978;6083.178770865877;6127.571070025056;5932.733027013881;5943.405659434195;5949.791757288474;5954.37479064692;5964.850878728066;6104;6105.368770830132;6116.0010150213275;6120.418560236771;6124.38756124384;5804.955108080586;5932.1694962706115;5947.405259474018;6015.789447369321;6060.727267273071 04:15:26 INFO - PROCESS | 1648 | |1;ext.html;496.62008563448967;505.0253011687442;505.7648193589981;511.8438876142777;534.478883088988;39627.405588916154;41141;41426.17147657045;41484;41489.377659335136;41669.37495937556;41978;42139.789301053526;42151.57848421515;42278.78860605693;8496.915030849705;9363.96996330403;9529.190018848407;9534.80930381392;9540.80918381632;8559.400841941035;11252.606158784403;11511.712207194845;11523.308601483937;11690.883091169064;11352.60265890694;11399.772004559904;11427.200095993283;11476.311421314747;11503;8585;8784.56077196144;11375.772484550303;11444.885551144507;11456.599019034376;11052.889471105267;11384.772304553946;11406.144539159513;11524.366159861203;11547;32732.345353093042;39568;39863.60136398628;39893;39907.60092399068;3194.37709646619;3290.144562413766;3437.3984552703178;3726.5341832270965;3732.0856390184576;28678.426431471464;28867.711322886924;30161;31079.844600777076;31086.222844428845;2932.516134837749;3184.6496885342594;3190.0429871038596;3221.32352206037;3317.303366293081;487050;488595.99999999645;488651;488833;489235;33372.83313583465;61594.69202653958;62051;62078;62168.689156554836;34034;34935.12662183466;35067.82466087653;35111.4733279001;35197;14450.927745361207;14597.12417254968;14601.342939567745;14609;14642.194679292741;11184.888151118548;11413.201075924606;11424.086073114131;12336.81494777579;12450.75098498043;7590.962045189739;7631.847363052708;7688.730894418669;7892.329152022027;7908.920910790934;2621;3155.936881262362;3227.8224697641385;3237.8704851805664;3238.6923242292414;903.8915330160423;909.9181073703398;954.9686338754129;995.5868314649484;1007.5566750629846;28250;29015.41969160605;29262.2991593292;29684.40631187364;29753.25616859596;1028.5320179318542;1035.6478797208986;1040.490159821682;1050.4380156616173;1124.9325040497597;14641.780373294408;15063.54809355721;15266.389344426321;15601;15658.37366505327;612556;620645;620677;623571;624337;56528;56886.7155664219;56923;56958;57025.429745702015;1145.6162185667895;1220.9816852747215;1257.8427696537933;1359.4086572341064;1365.904386692942;28521.42957140887;29678.554821678124;29691.96078137255;29792.55311170291;30528.694713052584;48900.2664960033;49399.75300123476;49431.50568494269;49444.75277623589;49463.50536494589;541684;543405.2829735826;543802;544029;545038;25824.22527324183;26612.536310503107;27081.1875643731;27159;27456.45087098287;43643.56356436395;43701.562984369746;43902;44653.10693786041;45136;36929.26141477209;37281.25437491289;37445.50653921547;38899.222015560095;39631.00922476961;36923.26153476969;37133.81433092817;37616.600987678736;37822.43266350952;37868.24263514659;37105.474979877945;37139.8143009294;37206.25587488181;37311.44032839564;37669;25980.35049123787;33737.66262337443;33780.49329260014;33897.49153762646;33952.66047339495;23803;24298.271051868473;24360.390990224678;24843.87578062098;24881.626775598743 04:15:26 INFO - PROCESS | 1648 | |2;jquery.html;288.0336471139328;354.29670152698947;355.4863222643281;380.5148435744425;407.5496576283207;14915.403383864646;15116.017458865179;16034.957727747702;17876;20455.59088818223;19169.94565298908;19248.615027699438;20365.185392584317;20599.27902523412;20607;5195.896082078356;5501.284832971708;5502.394736578973;5504;5509.173623956419;5200.349956255468;5379.488948549882;5434.266374039494;5457.536109430684;5462.8361149165585;5426.348838139429;5426.647267927592;5437.293151890249;5438.7280635968245;5438.782448702047;5426.62013659044;5800.245968024151;6087;6166.445019948226;6198.3801619838105;5714.285714285715;5990.64056156632;5996.670183139926;5996.820095397145;6004.249468816398;11062.944685276603;12717.872821271761;13179.538716144985;13193.20840749558;13226.338683065807;2044.5910817836493;2381.4760752634393;2403.435192729707;2514.4759266087976;2900.2024443278056;9512.857307140399;9969.65106221286;10354.9482252589;10372.48137593125;10423.895761042368;1709.6751617192695;1745.2669878650997;1806.9367572134913;1827.1321122466854;1868.392772348992;119928.40035799853;125260.37369813184;128893.42213155777;132776;133996.99999999904;9567.756191695174;11187.664370068911;11323.433828308716;11498;11549.826752598718;14491.92754036223;14497.637559061108;14505.492307769178;14513.564593062225;14540.27298635064;8318;8567.886174797237;8577.270931970728;8705.47767133974;8724.91275087241;8222.054463736747;8452.661893524313;8467.433524797809;8593.613287402082;8650.524221167896;4509.458864936229;4512.503624601291;4513.68404211702;4523.751193684313;4587.220172570671;1600.43184669443;1852.1850385830191;1902.3436914264557;1904.5714714189196;1905.361703829205;387.16758968217994;389.49949315129925;424.645421073401;825.3974598543098;900.9414388064755;11393.316401015965;11663.825042624367;11671.766564668658;11716.824247636292;11921.463534140985;429.8903779536198;430.4683715611218;433.87200775771225;434.2249085382577;450.9852831129958;5893.233879595625;5972.820815375546;5984.072468767348;6140.6929653517145;6155.47678447337;511180;514334;516705;624940;627130;74819.62590187014;118781.81218187707;120688.39655801666;125568.37215813862;131282;929.2890938432105;981.842905135175;1007.9647212347533;1017.9847302290324;1020.806046851096;18388.816111839245;18443.354482593044;18888.52778680544;19047.619047619246;19159.329423470113;46420.30369544392;50655.74672126616;50999.49000509948;52321.7383913078;53226.46773532215;67299.66350168218;73287.63356183186;74248;74855.8771618437;75127;6941.097657304518;7283.890741638986;7429.7399591014055;7538.057742782153;7644.12092609357;11001.779964400506;12469.625911222678;12649.304288264233;12943.611691649265;12950.287734174333;9871.42057270862;9912.454814985245;10806.243562950518;11231.494582743959;11284;10805.837912431478;10852.240343176218;10939.398333091756;11164.218504704593;11189.608363707232;10063.39619622825;10669.03978641926;10967.890321096687;11274.098072154373;11283.774324513628;7228.783136505913;7292.380147687506;7483.363914067365;7486.962565187139;7617.352525035212;10288.176945844465;11017.559297628006;11061.336319820837;11092.05717513988;11229.438528073728 04:15:26 INFO - PROCESS | 1648 | |3;mootools.html;1319.162331919231;1463.144060724476;1473.2339183624515;1633.4527933142397;1818.6908225601646;6806.319368063191;7567.167611562731;7972.800679983004;8516.446430981981;8527.616257268422;8395.328373730103;8478.872816907737;8480.448770829908;8487.95756021219;8500.0224974128;2300.2869110575734;2331.5919714049974;2334.043042352636;2334.5564342774906;2340.496793189464;2301.309607117858;2340.3447034830274;2347.272345572878;2348.424635964191;2349.447879748258;2318.8956496957676;2329.860208387494;2350.6239001759723;2351.27110595715;2357.233898982829;2284.7486776454575;2292.16336037346;2320.663503791949;2321.9535609287886;2325.174563030117;1934.7775005874223;2350.764923507653;2352.8588284702973;2353.882305884716;2358.2099996501192;8362.247397734232;8385.580720963988;8437.071922088566;8487;8524.65901363951;5042.369703787027;5248.606354523426;5254.579633629301;5280.023195708806;5288.180332048539;3103.5655008298854;3421.1789170599127;3564.48314994327;3629;3649.580298265707;1079.422508957704;1093.6445655162024;1717.467585048639;1742.2769550636472;1822.2164469278337;10569.577216911392;10954.178436617285;11030.779384412346;11041.171912106543;11051.778964420666;4387.802548885308;4489.19194544985;4536.251518499411;4540.909181816345;4555.2711566149255;8744.300455963508;8752.956235218782;8761.737147885573;8762.386632935633;8782.956085219534;5130.153524668388;5141.305923700253;5148.3564554430695;5156.484351564888;5156.793728250903;4657.883552911204;4774.761261936889;4813.95186048142;4823.469418363977;4849.587785038311;3619.7828130312264;3708.517892673935;3732.7760334380023;3759.0414444316525;3776.981115094407;1153.988460115405;1157.9652610421701;1166.6266794625637;1171.701216189883;1174.8002839517303;813.938954578409;898.7393655839865;899.8155378147464;919.5402298850574;934.528063328015;5431.511163995259;5648.265725455664;5671.744771485293;5705.229793977843;5827.155062515996;889.8442772514784;892.6117139044536;913.4291068082448;917.0966597901062;961.5144352102284;3676.3015027144756;3778.244351129785;3801.353769859131;3816.885493435201;3871.5547712012917;8240.258376746122;10331.276810623334;10342.948285258675;10354.792904141876;10366.637167699095;10187.54156062979;10331.225158113171;10339.896601034045;10343.534540945526;10365;7509.887351689729;7826.804329891685;7933.968584084032;7964.6415911282975;7991.64037618297;4808.61531077506;5409.0263752524925;5519.917201242065;5522.420145884625;5707.514861236841;9393.859092113487;9403.388779729295;9545.952270238604;9555.61777528891;9567.521623918916;8712;9078.31912606544;9122;9317.301202409712;9325.393849399767;6718.7312507499155;6869.553479023847;7122.893156602759;7128.572285662877;7202.855942881009;7687.846243075219;7902.920970790218;8008.83982320362;8087.272145506932;8100.9873765779275;7150.141982962078;7209.531380460253;7251.492395532262;7256.346928776436;7360.484766066324;7031.613261270679;7045.859082818418;7057.788266352018;7086.149662040588;7099.112610923635;6328.588641738273;6444.226692796894;6445.70994305248;6457.029766307551;6757.526973111835;4813.9037219256115;4832.34763306949;4867.3915760529935;4882.902341953212;4982.775775090202;4869.464358920587;4882.877928051827;4904.852854414374;4964.379452568429;5010.273510341053 04:15:26 INFO - PROCESS | 1648 | |4;prototype.html;265.99867000664995;305.15167833423084;307.79685407630956;313.48588315163136;319.24339315821504;4918;5328.547073498752;5360.9463905360935;5362.962074411718;5401.297831281928;4292.720973136744;4877.926831097537;5032.773525191366;5181.1606879557685;5187.922181167276;3366.0743295593784;3940.4089386591954;3992.923679686561;4010.2529767109477;4015.4177644241568;3272.836358182093;3825.8469661213517;4082.87751367458;4131.772752498611;4136;3833.0034191110235;3916;4014.257362387953;4054.1486287879434;4083.081306705997;3774.3960966245545;3953.4662820519293;3956.3076461619103;4033.495813023372;4076.1847630473876;3002.1293824791;3540.5574303212097;3551.6803487685975;3554.8400321985314;3557.2351944331836;4749.748974667687;4819;5020.567973435071;5136.460671629462;5219.4780521947505;4591.885202869921;4641.795147305037;4641.837535686234;4659.790309436084;4679.60223381013;5037.093323201822;5381.192821076831;5444.50999410055;5551.084875717871;5568.693721845297;3157.857896394668;3339.666033396665;3568.1614820517157;3619.9095022624383;3875.5543112542136;4822.011487645024;5472.015037293286;5543.473370029841;5897.616654917417;5903.085021821582;3271.8364081795817;3841.3085644584244;3869.496965394481;3882.2429626222615;3893.9610603894166;3675.46705727668;3799.5440547133967;3935.58676338986;3969.940450893239;3979.422983667352;4214.831406743757;4436.689431739812;4565.132624801277;4601.7699115044115;4618.422697162855;4743.928841067387;5631.859203519863;5640.830775076754;5659.292588426447;5716.885662286731;1067.4289255248405;1080.6379862746069;1142.4573327669457;1215.5563219424846;1254.4919307680404;754.8452567223706;816.8652172391489;826.5990994367727;851.1743608699552;860.6213266162869;4967.850964470999;4982.501749825061;5007.273945277987;5026.673266237683;5119.4129819756;4752.954350043055;5409.863928575043;5454.754536045887;5455.989847462112;5533.363663178786;4313.231575054213;5084.415292241366;5358.089124848785;5433.945660543423;5451.536619387317;2812.931086187236;2912.810667306618;2916.0960102368335;2916.6354205724283;2932.398858234057;4606.650608189847;5651.630950596949;5756.769729210785;5767.711614419262;5797.768089276382;5342.358916929993;6008.939910600925;6052.455279024909;6058.575899687068;6063.181470501514;5094.821681241138;5506.834794956158;6004.309504406961;6124.7739060048425;6134;5636;5658.462446067617;5858.531317494675;5903.645781253139;5909.320428150818;5780.132980052857;5792.652440853562;5794.348610645946;5798.391168927371;5837.883242335214;5663.461971112738;5927.911081333869;6009.558181962416;6113.759707355109;6137.723802428991;3308.818015009101;3832.9233415332096;3911.9804400977814;3915.3735402335487;3918.941215881821;4626.236670949255;5162;5351.973240133774;5882.794102206402;5932.79235226765;5496.1755736639825;5581.888362232814;5695.544356451391;5735.799247026334;5755.539556835527;5245.6852588844795;5634.74643641029;5664.405237450173;5684.857878553069;5691.658500489983;5238.240455133908;5289.841304760864;5491.121420572689;5557.72211389437;5613.287947176151;4660.580547750718;5337.919931200958;5547.916781248366;5594.8810237953385;5633.887322253614;4673.532646735299;4883.926741098958;5497.299808663535;5522.3649280333275;5530.340897730584 04:15:26 INFO - PROCESS | 1648 | |5;yui.html;738.1437532462343;751.7707099334702;760.1106705154968;770.7032792374933;785.4972817396864;15958.202089895502;16625.251863666133;16677.83322166778;16682.332706691734;16694.415695450647;14947.701045979074;15032.473863414783;15063.924680376611;15089.471868484605;15094.849051509482;650.7397041183517;671;823.234392015424;850.2177996243471;850.2560259772699;679.4937771360344;721.9602921839297;814.9714759983402;842.2125312832491;855.482433127958;889.4707648948865;890.2166093837446;892.6474042753109;893.2496702769672;893.9418937769057;886.5567216391804;886.592167602897;888.1030159538848;888.3248730964481;889.0886840988006;510.5532658923442;528.1022262154353;638.73811737188;654.8297442664893;660.7720336483906;11963.521459141624;14255.215963122024;14380;14397.496087636988;14484;12362.876371236262;12644.241345519298;12833;12844.678883027998;12973.221606703533;10836.620718274822;11426.71433214168;11450.370229637367;11946.880531194663;12283.877161228364;3668.706503479715;3703.703703703697;3722.9069273268115;3784.3188226119287;3798.601146879593;83379;87236.1276387228;87760;88718;89322;8331.16688331162;8480.491170529787;8511.872321915176;8949.149830766068;8998.775030624287;15495.992760470534;15607.297671604805;15632.531024069296;19703.40889773309;19771.110300036533;7580.3556697680215;7750.961245193738;7809.453338266267;7846.607669616496;8000.119986801447;3557.5019497270405;4043.7978101094827;4173.311403618369;4229.238737027365;4306.074194048295;3474.3919814032442;3573.5890372607482;3580.7135429165605;3581.5702115746276;3587.157018100744;639.4660458517097;641.6118248459708;642.1459458919626;649.8083065495596;666.1074160624786;553;600.4115966352998;617.4967401567708;672.2638710611873;679.1714108787272;7774.1837107104075;7879.724209652634;8792.120787921156;9149.954250228706;9453.810923781624;553.518438958101;610.6122612141322;628.7642134199675;628.9842753931189;667.4160109903834;4243.553465385998;4424.46906371231;4440.178566965122;4484.260097083945;4508.594226519629;73079.53840923152;74764.87852682214;98386.01613983912;113000;113036.43481782539;64991.35008649948;65990;83067;100339;103763.48118259512;92753;93505;93854;94683.0531694688;94897.52551237338;8675.132486751274;8702.1297870215;8740.694075707319;8741.91258087411;8822.2060014599;45200.77399613112;51305;51954.22068669049;61399;61439;70885;72131.63934180296;72475;76652.61673691595;78708.60645696965;12337.753244935231;12396.435440823276;12397.62807115788;12606.747865042831;12754.68113297175;13375.665608359868;13700.588982330546;14095.788563171356;14297.070690405091;14852.183129927958;12144.635660930186;12489.500419983098;12774.23354598727;12795.976321894412;13413.859821915246;12278.938605306916;13249.602511924657;13331.800022999856;13484.325783710972;13685.178889266675;11960.282383057045;12960.546380876624;12979.935100324816;13215.66960825987;13368.930485561326;10132.898671013196;10843.132549396187;11123.499442524944;11307.321560706385;11440.542378305108;7925.643346049326;9357.57890894925;10339.896601033894;10587;11592.188546801643 04:15:26 INFO - PROCESS | 1648 | __end_tp_report 04:15:26 INFO - PROCESS | 1648 | __start_cc_report 04:15:26 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,2739 04:15:26 INFO - PROCESS | 1648 | __end_cc_report 04:15:26 INFO - PROCESS | 1648 | __startTimestamp1479298526163__endTimestamp 04:15:26 INFO - PROCESS | 1648 | 04:15:26 INFO - PROCESS | 1648 | ------- Summary: start ------- 04:15:26 INFO - PROCESS | 1648 | Number of tests: 6 04:15:26 INFO - PROCESS | 1648 | 04:15:26 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5777.11 Median:5949.79 stddev:643.21 (10.8%) stddev-sans-first:616.29 04:15:26 INFO - PROCESS | 1648 | Values: 3347.2 3358.8 3360.8 3369.9 3414.1 5723.8 5758.7 5810.0 5810.2 5812.7 5689.7 5690.4 5733.9 5756.7 5804.5 5251.1 5273.4 5301.3 5313.2 5327.8 4305.2 5236.4 5243.1 5254.6 5264.8 5288.3 5328.5 5336.3 5339.8 5353.6 5334.0 5346.1 5346.9 5358.7 5387.6 5042.2 5177.1 5268.1 5272.4 5295.2 5671.1 5677.6 5707.5 5714.5 5721.1 6131.0 6169.2 6183.3 6235.3 6258.6 5296.9 6237.5 6270.1 6348.7 6353.3 5885.8 5905.0 5913.6 5927.8 5935.5 6257.5 6261.3 6274.1 6328.2 6329.9 6180.3 6227.2 6238.4 6239.9 6246.0 6299.5 6329.7 6335.8 6345.2 6351.3 6136.4 6145.2 6159.7 6165.0 6259.9 5641.2 6040.7 6247.1 6304.2 6304.7 4944.5 4956.0 4960.5 4966.7 4973.0 4401.2 4406.6 4490.3 4493.4 4501.4 5747.6 5788.8 5818.2 5900.4 5911.2 6302.3 6322.6 6337.5 6361.4 6368.3 5788.5 5810.0 5844.1 5850.1 5868.4 5361.5 5382.8 5406.0 5417.7 5443.7 6264.8 6290.4 6296.0 6356.2 6356.5 6332.2 6345.5 6347.1 6347.7 6354.1 6201.0 6204.0 6305.5 6343.9 6358.3 6140.7 6146.9 6150.6 6202.7 6270.3 6308.4 6313.2 6316.7 6319.6 6320.2 6101.6 6116.7 6133.5 6143.5 6158.9 5983.9 6049.3 6052.2 6083.2 6127.6 5932.7 5943.4 5949.8 5954.4 5964.9 6104.0 6105.4 6116.0 6120.4 6124.4 5805.0 5932.2 5947.4 6015.8 6060.7 04:15:26 INFO - PROCESS | 1648 | 04:15:26 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:67132.29 Median:35089.65 stddev:148011.17 (421.8%) stddev-sans-first:148341.83 04:15:26 INFO - PROCESS | 1648 | Values: 496.6 505.0 505.8 511.8 534.5 39627.4 41141.0 41426.2 41484.0 41489.4 41669.4 41978.0 42139.8 42151.6 42278.8 8496.9 9364.0 9529.2 9534.8 9540.8 8559.4 11252.6 11511.7 11523.3 11690.9 11352.6 11399.8 11427.2 11476.3 11503.0 8585.0 8784.6 11375.8 11444.9 11456.6 11052.9 11384.8 11406.1 11524.4 11547.0 32732.3 39568.0 39863.6 39893.0 39907.6 3194.4 3290.1 3437.4 3726.5 3732.1 28678.4 28867.7 30161.0 31079.8 31086.2 2932.5 3184.6 3190.0 3221.3 3317.3 487050.0 488596.0 488651.0 488833.0 489235.0 33372.8 61594.7 62051.0 62078.0 62168.7 34034.0 34935.1 35067.8 35111.5 35197.0 14450.9 14597.1 14601.3 14609.0 14642.2 11184.9 11413.2 11424.1 12336.8 12450.8 7591.0 7631.8 7688.7 7892.3 7908.9 2621.0 3155.9 3227.8 3237.9 3238.7 903.9 909.9 955.0 995.6 1007.6 28250.0 29015.4 29262.3 29684.4 29753.3 1028.5 1035.6 1040.5 1050.4 1124.9 14641.8 15063.5 15266.4 15601.0 15658.4 612556.0 620645.0 620677.0 623571.0 624337.0 56528.0 56886.7 56923.0 56958.0 57025.4 1145.6 1221.0 1257.8 1359.4 1365.9 28521.4 29678.6 29692.0 29792.6 30528.7 48900.3 49399.8 49431.5 49444.8 49463.5 541684.0 543405.3 543802.0 544029.0 545038.0 25824.2 26612.5 27081.2 27159.0 27456.5 43643.6 43701.6 43902.0 44653.1 45136.0 36929.3 37281.3 37445.5 38899.2 39631.0 36923.3 37133.8 37616.6 37822.4 37868.2 37105.5 37139.8 37206.3 37311.4 37669.0 25980.4 33737.7 33780.5 33897.5 33952.7 23803.0 24298.3 24360.4 24843.9 24881.6 04:15:26 INFO - PROCESS | 1648 | 04:15:26 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:32650.27 Median:383.84 stddev:94198.04 (24540.9%) stddev-sans-first:94430.95 04:15:26 INFO - PROCESS | 1648 | Values: 288.0 354.3 355.5 380.5 407.5 14915.4 15116.0 16035.0 17876.0 20455.6 19169.9 19248.6 20365.2 20599.3 20607.0 5195.9 5501.3 5502.4 5504.0 5509.2 5200.3 5379.5 5434.3 5457.5 5462.8 5426.3 5426.6 5437.3 5438.7 5438.8 5426.6 5800.2 6087.0 6166.4 6198.4 5714.3 5990.6 5996.7 5996.8 6004.2 11062.9 12717.9 13179.5 13193.2 13226.3 2044.6 2381.5 2403.4 2514.5 2900.2 9512.9 9969.7 10354.9 10372.5 10423.9 1709.7 1745.3 1806.9 1827.1 1868.4 119928.4 125260.4 128893.4 132776.0 133997.0 9567.8 11187.7 11323.4 11498.0 11549.8 14491.9 14497.6 14505.5 14513.6 14540.3 8318.0 8567.9 8577.3 8705.5 8724.9 8222.1 8452.7 8467.4 8593.6 8650.5 4509.5 4512.5 4513.7 4523.8 4587.2 1600.4 1852.2 1902.3 1904.6 1905.4 387.2 389.5 424.6 825.4 900.9 11393.3 11663.8 11671.8 11716.8 11921.5 429.9 430.5 433.9 434.2 451.0 5893.2 5972.8 5984.1 6140.7 6155.5 511180.0 514334.0 516705.0 624940.0 627130.0 74819.6 118781.8 120688.4 125568.4 131282.0 929.3 981.8 1008.0 1018.0 1020.8 18388.8 18443.4 18888.5 19047.6 19159.3 46420.3 50655.7 50999.5 52321.7 53226.5 67299.7 73287.6 74248.0 74855.9 75127.0 6941.1 7283.9 7429.7 7538.1 7644.1 11001.8 12469.6 12649.3 12943.6 12950.3 9871.4 9912.5 10806.2 11231.5 11284.0 10805.8 10852.2 10939.4 11164.2 11189.6 10063.4 10669.0 10967.9 11274.1 11283.8 7228.8 7292.4 7483.4 7487.0 7617.4 10288.2 11017.6 11061.3 11092.1 11229.4 04:15:26 INFO - PROCESS | 1648 | 04:15:26 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5444.74 Median:5086.26 stddev:2956.59 (58.1%) stddev-sans-first:2948.62 04:15:26 INFO - PROCESS | 1648 | Values: 1319.2 1463.1 1473.2 1633.5 1818.7 6806.3 7567.2 7972.8 8516.4 8527.6 8395.3 8478.9 8480.4 8488.0 8500.0 2300.3 2331.6 2334.0 2334.6 2340.5 2301.3 2340.3 2347.3 2348.4 2349.4 2318.9 2329.9 2350.6 2351.3 2357.2 2284.7 2292.2 2320.7 2322.0 2325.2 1934.8 2350.8 2352.9 2353.9 2358.2 8362.2 8385.6 8437.1 8487.0 8524.7 5042.4 5248.6 5254.6 5280.0 5288.2 3103.6 3421.2 3564.5 3629.0 3649.6 1079.4 1093.6 1717.5 1742.3 1822.2 10569.6 10954.2 11030.8 11041.2 11051.8 4387.8 4489.2 4536.3 4540.9 4555.3 8744.3 8753.0 8761.7 8762.4 8783.0 5130.2 5141.3 5148.4 5156.5 5156.8 4657.9 4774.8 4814.0 4823.5 4849.6 3619.8 3708.5 3732.8 3759.0 3777.0 1154.0 1158.0 1166.6 1171.7 1174.8 813.9 898.7 899.8 919.5 934.5 5431.5 5648.3 5671.7 5705.2 5827.2 889.8 892.6 913.4 917.1 961.5 3676.3 3778.2 3801.4 3816.9 3871.6 8240.3 10331.3 10342.9 10354.8 10366.6 10187.5 10331.2 10339.9 10343.5 10365.0 7509.9 7826.8 7934.0 7964.6 7991.6 4808.6 5409.0 5519.9 5522.4 5707.5 9393.9 9403.4 9546.0 9555.6 9567.5 8712.0 9078.3 9122.0 9317.3 9325.4 6718.7 6869.6 7122.9 7128.6 7202.9 7687.8 7902.9 8008.8 8087.3 8101.0 7150.1 7209.5 7251.5 7256.3 7360.5 7031.6 7045.9 7057.8 7086.1 7099.1 6328.6 6444.2 6445.7 6457.0 6757.5 4813.9 4832.3 4867.4 4882.9 4982.8 4869.5 4882.9 4904.9 4964.4 5010.3 04:15:26 INFO - PROCESS | 1648 | 04:15:26 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4522.39 Median:5060.75 stddev:1426.82 (28.2%) stddev-sans-first:1394.60 04:15:26 INFO - PROCESS | 1648 | Values: 266.0 305.2 307.8 313.5 319.2 4918.0 5328.5 5360.9 5363.0 5401.3 4292.7 4877.9 5032.8 5181.2 5187.9 3366.1 3940.4 3992.9 4010.3 4015.4 3272.8 3825.8 4082.9 4131.8 4136.0 3833.0 3916.0 4014.3 4054.1 4083.1 3774.4 3953.5 3956.3 4033.5 4076.2 3002.1 3540.6 3551.7 3554.8 3557.2 4749.7 4819.0 5020.6 5136.5 5219.5 4591.9 4641.8 4641.8 4659.8 4679.6 5037.1 5381.2 5444.5 5551.1 5568.7 3157.9 3339.7 3568.2 3619.9 3875.6 4822.0 5472.0 5543.5 5897.6 5903.1 3271.8 3841.3 3869.5 3882.2 3894.0 3675.5 3799.5 3935.6 3969.9 3979.4 4214.8 4436.7 4565.1 4601.8 4618.4 4743.9 5631.9 5640.8 5659.3 5716.9 1067.4 1080.6 1142.5 1215.6 1254.5 754.8 816.9 826.6 851.2 860.6 4967.9 4982.5 5007.3 5026.7 5119.4 4753.0 5409.9 5454.8 5456.0 5533.4 4313.2 5084.4 5358.1 5433.9 5451.5 2812.9 2912.8 2916.1 2916.6 2932.4 4606.7 5651.6 5756.8 5767.7 5797.8 5342.4 6008.9 6052.5 6058.6 6063.2 5094.8 5506.8 6004.3 6124.8 6134.0 5636.0 5658.5 5858.5 5903.6 5909.3 5780.1 5792.7 5794.3 5798.4 5837.9 5663.5 5927.9 6009.6 6113.8 6137.7 3308.8 3832.9 3912.0 3915.4 3918.9 4626.2 5162.0 5352.0 5882.8 5932.8 5496.2 5581.9 5695.5 5735.8 5755.5 5245.7 5634.7 5664.4 5684.9 5691.7 5238.2 5289.8 5491.1 5557.7 5613.3 4660.6 5337.9 5547.9 5594.9 5633.9 4673.5 4883.9 5497.3 5522.4 5530.3 04:15:26 INFO - PROCESS | 1648 | 04:15:26 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:19923.44 Median:26241.16 stddev:28885.64 (110.1%) stddev-sans-first:28930.75 04:15:26 INFO - PROCESS | 1648 | Values: 738.1 751.8 760.1 770.7 785.5 15958.2 16625.3 16677.8 16682.3 16694.4 14947.7 15032.5 15063.9 15089.5 15094.8 650.7 671.0 823.2 850.2 850.3 679.5 722.0 815.0 842.2 855.5 889.5 890.2 892.6 893.2 893.9 886.6 886.6 888.1 888.3 889.1 510.6 528.1 638.7 654.8 660.8 11963.5 14255.2 14380.0 14397.5 14484.0 12362.9 12644.2 12833.0 12844.7 12973.2 10836.6 11426.7 11450.4 11946.9 12283.9 3668.7 3703.7 3722.9 3784.3 3798.6 83379.0 87236.1 87760.0 88718.0 89322.0 8331.2 8480.5 8511.9 8949.1 8998.8 15496.0 15607.3 15632.5 19703.4 19771.1 7580.4 7751.0 7809.5 7846.6 8000.1 3557.5 4043.8 4173.3 4229.2 4306.1 3474.4 3573.6 3580.7 3581.6 3587.2 639.5 641.6 642.1 649.8 666.1 553.0 600.4 617.5 672.3 679.2 7774.2 7879.7 8792.1 9150.0 9453.8 553.5 610.6 628.8 629.0 667.4 4243.6 4424.5 4440.2 4484.3 4508.6 73079.5 74764.9 98386.0 113000.0 113036.4 64991.4 65990.0 83067.0 100339.0 103763.5 92753.0 93505.0 93854.0 94683.1 94897.5 8675.1 8702.1 8740.7 8741.9 8822.2 45200.8 51305.0 51954.2 61399.0 61439.0 70885.0 72131.6 72475.0 76652.6 78708.6 12337.8 12396.4 12397.6 12606.7 12754.7 13375.7 13700.6 14095.8 14297.1 14852.2 12144.6 12489.5 12774.2 12796.0 13413.9 12278.9 13249.6 13331.8 13484.3 13685.2 11960.3 12960.5 12979.9 13215.7 13368.9 10132.9 10843.1 11123.5 11307.3 11440.5 7925.6 9357.6 10339.9 10587.0 11592.2 04:15:26 INFO - PROCESS | 1648 | -------- Summary: end -------- 04:15:26 INFO - PROCESS | 1648 | 04:15:26 INFO - TEST-INFO | 1648: exit 0 04:15:26 INFO - TEST-OK | dromaeo_css | took 1119979ms 04:15:26 INFO - TEST-START | kraken 04:15:26 INFO - operating with platform_type : mac_ 04:15:26 INFO - Initialising browser for kraken test... 04:15:26 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpa7K0KO/profile http://localhost:49246/getInfo.html) 04:15:28 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 04:15:29 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 04:15:29 INFO - PROCESS | 1654 | colorDepth:24 04:15:29 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 04:15:29 INFO - PROCESS | 1654 | __metrics 04:15:29 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:15:29 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:15:29 INFO - TEST-INFO | 1654: exit 0 04:15:29 INFO - Browser initialized. 04:15:29 INFO - Running cycle 1/1 for kraken test... 04:15:29 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpa7K0KO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:15:41 INFO - PROCESS | 1656 | RSS: Main: 217841664 04:15:41 INFO - PROCESS | 1656 | 04:15:44 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 04:15:45 INFO - PROCESS | 1656 | RSS: Main: 267440128 04:15:45 INFO - PROCESS | 1656 | 04:15:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 04:15:48 INFO - PROCESS | 1656 | RSS: Main: 276840448 04:15:48 INFO - PROCESS | 1656 | 04:15:52 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 04:15:52 INFO - PROCESS | 1656 | RSS: Main: 372658176 04:15:52 INFO - PROCESS | 1656 | 04:15:54 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 04:15:55 INFO - PROCESS | 1656 | RSS: Main: 278110208 04:15:55 INFO - PROCESS | 1656 | 04:15:57 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:15:58 INFO - PROCESS | 1656 | RSS: Main: 279461888 04:15:58 INFO - PROCESS | 1656 | 04:16:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 04:16:01 INFO - PROCESS | 1656 | RSS: Main: 319352832 04:16:01 INFO - PROCESS | 1656 | 04:16:04 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 04:16:05 INFO - PROCESS | 1656 | RSS: Main: 320417792 04:16:05 INFO - PROCESS | 1656 | 04:16:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 04:16:08 INFO - PROCESS | 1656 | RSS: Main: 316792832 04:16:08 INFO - PROCESS | 1656 | 04:16:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:16:10 INFO - PROCESS | 1656 | RSS: Main: 296112128 04:16:10 INFO - PROCESS | 1656 | 04:16:12 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:16:13 INFO - PROCESS | 1656 | RSS: Main: 296042496 04:16:13 INFO - PROCESS | 1656 | 04:16:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:16:15 INFO - PROCESS | 1656 | RSS: Main: 301244416 04:16:15 INFO - PROCESS | 1656 | 04:16:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:16:19 INFO - PROCESS | 1656 | RSS: Main: 317612032 04:16:19 INFO - PROCESS | 1656 | 04:16:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:16:23 INFO - PROCESS | 1656 | RSS: Main: 310812672 04:16:23 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 04:16:25 INFO - PROCESS | 1656 | RSS: Main: 354988032 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | __start_tp_report 04:16:25 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 04:16:25 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 04:16:25 INFO - PROCESS | 1656 | |i|pagename|runs| 04:16:25 INFO - PROCESS | 1656 | |0;ai-astar;159;169;129;114;114;115;115;113;114;115 04:16:25 INFO - PROCESS | 1656 | |1;audio-beat-detection;146;112;113;147;114;98;132;142;157;122 04:16:25 INFO - PROCESS | 1656 | |2;audio-dft;183;183;195;260;202;181;183;182;183;185 04:16:25 INFO - PROCESS | 1656 | |3;audio-fft;81;60;59;87;64;59;60;64;99;71 04:16:25 INFO - PROCESS | 1656 | |4;audio-oscillator;89;70;96;69;68;69;89;68;69;89 04:16:25 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;88;87;90;86;97;130;114;86;89;86 04:16:25 INFO - PROCESS | 1656 | |6;imaging-darkroom;98;97;98;98;97;97;97;97;96;97 04:16:25 INFO - PROCESS | 1656 | |7;imaging-desaturate;83;82;82;79;84;81;84;86;84;80 04:16:25 INFO - PROCESS | 1656 | |8;json-parse-financial;56;55;56;57;59;58;59;59;59;56 04:16:25 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;65;81;74;73;71;68;69;70;69;73 04:16:25 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;83;84;81;86;84;83;85;83;81;90 04:16:25 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;147;146;145;141;148;164;145;142;211;173 04:16:25 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;165;222;232;167;167;165;144;225;220;171 04:16:25 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;69;67;67;67;67;65;66;67;65;65 04:16:25 INFO - PROCESS | 1656 | __end_tp_report 04:16:25 INFO - PROCESS | 1656 | __start_cc_report 04:16:25 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2048 04:16:25 INFO - PROCESS | 1656 | __end_cc_report 04:16:25 INFO - PROCESS | 1656 | __startTimestamp1479298585297__endTimestamp 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | ------- Summary: start ------- 04:16:25 INFO - PROCESS | 1656 | Number of tests: 14 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:125.70 Median:115.00 stddev:20.84 (18.1%) stddev-sans-first:18.30 04:16:25 INFO - PROCESS | 1656 | Values: 159.0 169.0 129.0 114.0 114.0 115.0 115.0 113.0 114.0 115.0 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:144.00 stddev:19.29 (13.4%) stddev-sans-first:19.37 04:16:25 INFO - PROCESS | 1656 | Values: 146.0 112.0 113.0 147.0 114.0 98.0 132.0 142.0 157.0 122.0 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:193.70 Median:184.00 stddev:24.26 (13.2%) stddev-sans-first:25.42 04:16:25 INFO - PROCESS | 1656 | Values: 183.0 183.0 195.0 260.0 202.0 181.0 183.0 182.0 183.0 185.0 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:70.40 Median:67.50 stddev:14.00 (20.7%) stddev-sans-first:14.32 04:16:25 INFO - PROCESS | 1656 | Values: 81.0 60.0 59.0 87.0 64.0 59.0 60.0 64.0 99.0 71.0 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:79.50 stddev:11.51 (14.5%) stddev-sans-first:11.45 04:16:25 INFO - PROCESS | 1656 | Values: 89.0 70.0 96.0 69.0 68.0 69.0 89.0 68.0 69.0 89.0 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:95.30 Median:87.50 stddev:14.93 (17.1%) stddev-sans-first:15.60 04:16:25 INFO - PROCESS | 1656 | Values: 88.0 87.0 90.0 86.0 97.0 130.0 114.0 86.0 89.0 86.0 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:97.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 04:16:25 INFO - PROCESS | 1656 | Values: 98.0 97.0 98.0 98.0 97.0 97.0 97.0 97.0 96.0 97.0 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:82.50 Median:83.50 stddev:2.12 (2.5%) stddev-sans-first:2.24 04:16:25 INFO - PROCESS | 1656 | Values: 83.0 82.0 82.0 79.0 84.0 81.0 84.0 86.0 84.0 80.0 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.50 stddev:1.58 (2.7%) stddev-sans-first:1.59 04:16:25 INFO - PROCESS | 1656 | Values: 56.0 55.0 56.0 57.0 59.0 58.0 59.0 59.0 59.0 56.0 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.00 stddev:4.35 (6.0%) stddev-sans-first:3.97 04:16:25 INFO - PROCESS | 1656 | Values: 65.0 81.0 74.0 73.0 71.0 68.0 69.0 70.0 69.0 73.0 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:2.62 (3.1%) stddev-sans-first:2.76 04:16:25 INFO - PROCESS | 1656 | Values: 83.0 84.0 81.0 86.0 84.0 83.0 85.0 83.0 81.0 90.0 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:156.20 Median:147.50 stddev:21.82 (14.8%) stddev-sans-first:22.89 04:16:25 INFO - PROCESS | 1656 | Values: 147.0 146.0 145.0 141.0 148.0 164.0 145.0 142.0 211.0 173.0 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.80 Median:195.50 stddev:32.74 (16.7%) stddev-sans-first:33.67 04:16:25 INFO - PROCESS | 1656 | Values: 165.0 222.0 232.0 167.0 167.0 165.0 144.0 225.0 220.0 171.0 04:16:25 INFO - PROCESS | 1656 | 04:16:25 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:1.27 (1.9%) stddev-sans-first:0.97 04:16:25 INFO - PROCESS | 1656 | Values: 69.0 67.0 67.0 67.0 67.0 65.0 66.0 67.0 65.0 65.0 04:16:25 INFO - PROCESS | 1656 | -------- Summary: end -------- 04:16:25 INFO - PROCESS | 1656 | 04:16:26 INFO - TEST-INFO | 1656: exit 0 04:16:26 INFO - TEST-OK | kraken | took 59274ms 04:16:26 INFO - SUITE-END | took 1179s 04:16:26 INFO - Completed test suite (00:19:39) 04:16:26 INFO - javascript benchmark 04:16:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3347.2134048498597, 3358.7984720916734, 3360.831958402079, 3369.9326013479745, 3414.1123307939943, 5723.828285151447, 5758.683272420015, 5810.0, 5810.244668193134, 5812.680302583356, 5689.715514224283, 5690.430956904307, 5733.856653583663, 5756.683382413966, 5804.477597016267, 5251.054810134175, 5273.4462881397285, 5301.257823904656, 5313.22958171065, 5327.813526526553, 4305.182015417076, 5236.3978142513715, 5243.082460569404, 5254.57963362932, 5264.789408423659, 5288.2596436498725, 5328.49379308965, 5336.30628018359, 5339.8398048058425, 5353.625246232766, 5334.013207556595, 5346.144616861322, 5346.91979620306, 5358.732063396834, 5387.622866399353, 5042.193249080166, 5177.119889618773, 5268.104422248189, 5272.367315922075, 5295.205719142121, 5671.120976248688, 5677.630953987978, 5707.486326230659, 5714.51426628737, 5721.056025755745, 6130.990686281824, 6169.167162433059, 6183.319834818167, 6235.345288744662, 6258.624482531017, 5296.894062118775, 6237.469815065725, 6270.059491076329, 6348.714307856158, 6353.30113687494, 5885.823425297205, 5904.97047514764, 5913.586048976616, 5927.822165335004, 5935.52515798735, 6257.4681152102585, 6261.279952805395, 6274.090256912722, 6328.177336946167, 6329.8734025320155, 6180.258368995659, 6227.221597300338, 6238.376162383725, 6239.906401403982, 6246.0, 6299.496040316763, 6329.715162817684, 6335.841603959846, 6345.238571371465, 6351.333110023381, 6136.447719705248, 6145.187169784924, 6159.692015399212, 6165.013597824327, 6259.937400625936, 5641.238432811518, 6040.6710523684715, 6247.094171345129, 6304.243490781136, 6304.653244071528, 4944.480829512921, 4956.008798240367, 4960.50394960501, 4966.6771659842, 4972.950270497321, 4401.163778882003, 4406.62543683784, 4490.281554951257, 4493.415855938707, 4501.369808226851, 5747.626404283792, 5788.82633520995, 5818.2436283282905, 5900.4099590041515, 5911.172435858984, 6302.306746257908, 6322.557420980487, 6337.461315788118, 6361.363863613602, 6368.331325210786, 5788.5369170466265, 5810.012297909366, 5844.1233814927255, 5850.12248162779, 5868.354481007086, 5361.544268737201, 5382.757775900094, 5405.972970135203, 5417.702026388586, 5443.673379597237, 6264.812055638338, 6290.370962903673, 6295.96852015737, 6356.2054743157105, 6356.491480681442, 6332.208473940757, 6345.4923606112325, 6347.111404403295, 6347.682615869096, 6354.078658594385, 6200.968995155175, 6203.96898015507, 6305.495560355253, 6343.9365606343345, 6358.300586935341, 6140.718981675666, 6146.877062458636, 6150.569460137747, 6202.689865506797, 6270.278917924497, 6308.369163083838, 6313.2424109107205, 6316.684165791784, 6319.557630965788, 6320.241571011508, 6101.572889897665, 6116.663583502772, 6133.478654314434, 6143.508519318533, 6158.938410615837, 5983.880322393615, 6049.304330002106, 6052.182955300978, 6083.178770865877, 6127.571070025056, 5932.733027013881, 5943.405659434195, 5949.791757288474, 5954.37479064692, 5964.850878728066, 6104.0, 6105.368770830132, 6116.0010150213275, 6120.418560236771, 6124.38756124384, 5804.955108080586, 5932.1694962706115, 5947.405259474018, 6015.789447369321, 6060.727267273071], "value": 5735.694481869389, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.62008563448967, 505.0253011687442, 505.7648193589981, 511.8438876142777, 534.478883088988, 39627.405588916154, 41141.0, 41426.17147657045, 41484.0, 41489.377659335136, 41669.37495937556, 41978.0, 42139.789301053526, 42151.57848421515, 42278.78860605693, 8496.915030849705, 9363.96996330403, 9529.190018848407, 9534.80930381392, 9540.80918381632, 8559.400841941035, 11252.606158784403, 11511.712207194845, 11523.308601483937, 11690.883091169064, 11352.60265890694, 11399.772004559904, 11427.200095993283, 11476.311421314747, 11503.0, 8585.0, 8784.56077196144, 11375.772484550303, 11444.885551144507, 11456.599019034376, 11052.889471105267, 11384.772304553946, 11406.144539159513, 11524.366159861203, 11547.0, 32732.345353093042, 39568.0, 39863.60136398628, 39893.0, 39907.60092399068, 3194.37709646619, 3290.144562413766, 3437.3984552703178, 3726.5341832270965, 3732.0856390184576, 28678.426431471464, 28867.711322886924, 30161.0, 31079.844600777076, 31086.222844428845, 2932.516134837749, 3184.6496885342594, 3190.0429871038596, 3221.32352206037, 3317.303366293081, 487050.0, 488595.99999999645, 488651.0, 488833.0, 489235.0, 33372.83313583465, 61594.69202653958, 62051.0, 62078.0, 62168.689156554836, 34034.0, 34935.12662183466, 35067.82466087653, 35111.4733279001, 35197.0, 14450.927745361207, 14597.12417254968, 14601.342939567745, 14609.0, 14642.194679292741, 11184.888151118548, 11413.201075924606, 11424.086073114131, 12336.81494777579, 12450.75098498043, 7590.962045189739, 7631.847363052708, 7688.730894418669, 7892.329152022027, 7908.920910790934, 2621.0, 3155.936881262362, 3227.8224697641385, 3237.8704851805664, 3238.6923242292414, 903.8915330160423, 909.9181073703398, 954.9686338754129, 995.5868314649484, 1007.5566750629846, 28250.0, 29015.41969160605, 29262.2991593292, 29684.40631187364, 29753.25616859596, 1028.5320179318542, 1035.6478797208986, 1040.490159821682, 1050.4380156616173, 1124.9325040497597, 14641.780373294408, 15063.54809355721, 15266.389344426321, 15601.0, 15658.37366505327, 612556.0, 620645.0, 620677.0, 623571.0, 624337.0, 56528.0, 56886.7155664219, 56923.0, 56958.0, 57025.429745702015, 1145.6162185667895, 1220.9816852747215, 1257.8427696537933, 1359.4086572341064, 1365.904386692942, 28521.42957140887, 29678.554821678124, 29691.96078137255, 29792.55311170291, 30528.694713052584, 48900.2664960033, 49399.75300123476, 49431.50568494269, 49444.75277623589, 49463.50536494589, 541684.0, 543405.2829735826, 543802.0, 544029.0, 545038.0, 25824.22527324183, 26612.536310503107, 27081.1875643731, 27159.0, 27456.45087098287, 43643.56356436395, 43701.562984369746, 43902.0, 44653.10693786041, 45136.0, 36929.26141477209, 37281.25437491289, 37445.50653921547, 38899.222015560095, 39631.00922476961, 36923.26153476969, 37133.81433092817, 37616.600987678736, 37822.43266350952, 37868.24263514659, 37105.474979877945, 37139.8143009294, 37206.25587488181, 37311.44032839564, 37669.0, 25980.35049123787, 33737.66262337443, 33780.49329260014, 33897.49153762646, 33952.66047339495, 23803.0, 24298.271051868473, 24360.390990224678, 24843.87578062098, 24881.626775598743], "value": 18573.013367416756, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.0336471139328, 354.29670152698947, 355.4863222643281, 380.5148435744425, 407.5496576283207, 14915.403383864646, 15116.017458865179, 16034.957727747702, 17876.0, 20455.59088818223, 19169.94565298908, 19248.615027699438, 20365.185392584317, 20599.27902523412, 20607.0, 5195.896082078356, 5501.284832971708, 5502.394736578973, 5504.0, 5509.173623956419, 5200.349956255468, 5379.488948549882, 5434.266374039494, 5457.536109430684, 5462.8361149165585, 5426.348838139429, 5426.647267927592, 5437.293151890249, 5438.7280635968245, 5438.782448702047, 5426.62013659044, 5800.245968024151, 6087.0, 6166.445019948226, 6198.3801619838105, 5714.285714285715, 5990.64056156632, 5996.670183139926, 5996.820095397145, 6004.249468816398, 11062.944685276603, 12717.872821271761, 13179.538716144985, 13193.20840749558, 13226.338683065807, 2044.5910817836493, 2381.4760752634393, 2403.435192729707, 2514.4759266087976, 2900.2024443278056, 9512.857307140399, 9969.65106221286, 10354.9482252589, 10372.48137593125, 10423.895761042368, 1709.6751617192695, 1745.2669878650997, 1806.9367572134913, 1827.1321122466854, 1868.392772348992, 119928.40035799853, 125260.37369813184, 128893.42213155777, 132776.0, 133996.99999999904, 9567.756191695174, 11187.664370068911, 11323.433828308716, 11498.0, 11549.826752598718, 14491.92754036223, 14497.637559061108, 14505.492307769178, 14513.564593062225, 14540.27298635064, 8318.0, 8567.886174797237, 8577.270931970728, 8705.47767133974, 8724.91275087241, 8222.054463736747, 8452.661893524313, 8467.433524797809, 8593.613287402082, 8650.524221167896, 4509.458864936229, 4512.503624601291, 4513.68404211702, 4523.751193684313, 4587.220172570671, 1600.43184669443, 1852.1850385830191, 1902.3436914264557, 1904.5714714189196, 1905.361703829205, 387.16758968217994, 389.49949315129925, 424.645421073401, 825.3974598543098, 900.9414388064755, 11393.316401015965, 11663.825042624367, 11671.766564668658, 11716.824247636292, 11921.463534140985, 429.8903779536198, 430.4683715611218, 433.87200775771225, 434.2249085382577, 450.9852831129958, 5893.233879595625, 5972.820815375546, 5984.072468767348, 6140.6929653517145, 6155.47678447337, 511180.0, 514334.0, 516705.0, 624940.0, 627130.0, 74819.62590187014, 118781.81218187707, 120688.39655801666, 125568.37215813862, 131282.0, 929.2890938432105, 981.842905135175, 1007.9647212347533, 1017.9847302290324, 1020.806046851096, 18388.816111839245, 18443.354482593044, 18888.52778680544, 19047.619047619246, 19159.329423470113, 46420.30369544392, 50655.74672126616, 50999.49000509948, 52321.7383913078, 53226.46773532215, 67299.66350168218, 73287.63356183186, 74248.0, 74855.8771618437, 75127.0, 6941.097657304518, 7283.890741638986, 7429.7399591014055, 7538.057742782153, 7644.12092609357, 11001.779964400506, 12469.625911222678, 12649.304288264233, 12943.611691649265, 12950.287734174333, 9871.42057270862, 9912.454814985245, 10806.243562950518, 11231.494582743959, 11284.0, 10805.837912431478, 10852.240343176218, 10939.398333091756, 11164.218504704593, 11189.608363707232, 10063.39619622825, 10669.03978641926, 10967.890321096687, 11274.098072154373, 11283.774324513628, 7228.783136505913, 7292.380147687506, 7483.363914067365, 7486.962565187139, 7617.352525035212, 10288.176945844465, 11017.559297628006, 11061.336319820837, 11092.05717513988, 11229.438528073728], "value": 8548.718207011929, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1319.162331919231, 1463.144060724476, 1473.2339183624515, 1633.4527933142397, 1818.6908225601646, 6806.319368063191, 7567.167611562731, 7972.800679983004, 8516.446430981981, 8527.616257268422, 8395.328373730103, 8478.872816907737, 8480.448770829908, 8487.95756021219, 8500.0224974128, 2300.2869110575734, 2331.5919714049974, 2334.043042352636, 2334.5564342774906, 2340.496793189464, 2301.309607117858, 2340.3447034830274, 2347.272345572878, 2348.424635964191, 2349.447879748258, 2318.8956496957676, 2329.860208387494, 2350.6239001759723, 2351.27110595715, 2357.233898982829, 2284.7486776454575, 2292.16336037346, 2320.663503791949, 2321.9535609287886, 2325.174563030117, 1934.7775005874223, 2350.764923507653, 2352.8588284702973, 2353.882305884716, 2358.2099996501192, 8362.247397734232, 8385.580720963988, 8437.071922088566, 8487.0, 8524.65901363951, 5042.369703787027, 5248.606354523426, 5254.579633629301, 5280.023195708806, 5288.180332048539, 3103.5655008298854, 3421.1789170599127, 3564.48314994327, 3629.0, 3649.580298265707, 1079.422508957704, 1093.6445655162024, 1717.467585048639, 1742.2769550636472, 1822.2164469278337, 10569.577216911392, 10954.178436617285, 11030.779384412346, 11041.171912106543, 11051.778964420666, 4387.802548885308, 4489.19194544985, 4536.251518499411, 4540.909181816345, 4555.2711566149255, 8744.300455963508, 8752.956235218782, 8761.737147885573, 8762.386632935633, 8782.956085219534, 5130.153524668388, 5141.305923700253, 5148.3564554430695, 5156.484351564888, 5156.793728250903, 4657.883552911204, 4774.761261936889, 4813.95186048142, 4823.469418363977, 4849.587785038311, 3619.7828130312264, 3708.517892673935, 3732.7760334380023, 3759.0414444316525, 3776.981115094407, 1153.988460115405, 1157.9652610421701, 1166.6266794625637, 1171.701216189883, 1174.8002839517303, 813.938954578409, 898.7393655839865, 899.8155378147464, 919.5402298850574, 934.528063328015, 5431.511163995259, 5648.265725455664, 5671.744771485293, 5705.229793977843, 5827.155062515996, 889.8442772514784, 892.6117139044536, 913.4291068082448, 917.0966597901062, 961.5144352102284, 3676.3015027144756, 3778.244351129785, 3801.353769859131, 3816.885493435201, 3871.5547712012917, 8240.258376746122, 10331.276810623334, 10342.948285258675, 10354.792904141876, 10366.637167699095, 10187.54156062979, 10331.225158113171, 10339.896601034045, 10343.534540945526, 10365.0, 7509.887351689729, 7826.804329891685, 7933.968584084032, 7964.6415911282975, 7991.64037618297, 4808.61531077506, 5409.0263752524925, 5519.917201242065, 5522.420145884625, 5707.514861236841, 9393.859092113487, 9403.388779729295, 9545.952270238604, 9555.61777528891, 9567.521623918916, 8712.0, 9078.31912606544, 9122.0, 9317.301202409712, 9325.393849399767, 6718.7312507499155, 6869.553479023847, 7122.893156602759, 7128.572285662877, 7202.855942881009, 7687.846243075219, 7902.920970790218, 8008.83982320362, 8087.272145506932, 8100.9873765779275, 7150.141982962078, 7209.531380460253, 7251.492395532262, 7256.346928776436, 7360.484766066324, 7031.613261270679, 7045.859082818418, 7057.788266352018, 7086.149662040588, 7099.112610923635, 6328.588641738273, 6444.226692796894, 6445.70994305248, 6457.029766307551, 6757.526973111835, 4813.9037219256115, 4832.34763306949, 4867.3915760529935, 4882.902341953212, 4982.775775090202, 4869.464358920587, 4882.877928051827, 4904.852854414374, 4964.379452568429, 5010.273510341053], "value": 4445.304527037638, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.99867000664995, 305.15167833423084, 307.79685407630956, 313.48588315163136, 319.24339315821504, 4918.0, 5328.547073498752, 5360.9463905360935, 5362.962074411718, 5401.297831281928, 4292.720973136744, 4877.926831097537, 5032.773525191366, 5181.1606879557685, 5187.922181167276, 3366.0743295593784, 3940.4089386591954, 3992.923679686561, 4010.2529767109477, 4015.4177644241568, 3272.836358182093, 3825.8469661213517, 4082.87751367458, 4131.772752498611, 4136.0, 3833.0034191110235, 3916.0, 4014.257362387953, 4054.1486287879434, 4083.081306705997, 3774.3960966245545, 3953.4662820519293, 3956.3076461619103, 4033.495813023372, 4076.1847630473876, 3002.1293824791, 3540.5574303212097, 3551.6803487685975, 3554.8400321985314, 3557.2351944331836, 4749.748974667687, 4819.0, 5020.567973435071, 5136.460671629462, 5219.4780521947505, 4591.885202869921, 4641.795147305037, 4641.837535686234, 4659.790309436084, 4679.60223381013, 5037.093323201822, 5381.192821076831, 5444.50999410055, 5551.084875717871, 5568.693721845297, 3157.857896394668, 3339.666033396665, 3568.1614820517157, 3619.9095022624383, 3875.5543112542136, 4822.011487645024, 5472.015037293286, 5543.473370029841, 5897.616654917417, 5903.085021821582, 3271.8364081795817, 3841.3085644584244, 3869.496965394481, 3882.2429626222615, 3893.9610603894166, 3675.46705727668, 3799.5440547133967, 3935.58676338986, 3969.940450893239, 3979.422983667352, 4214.831406743757, 4436.689431739812, 4565.132624801277, 4601.7699115044115, 4618.422697162855, 4743.928841067387, 5631.859203519863, 5640.830775076754, 5659.292588426447, 5716.885662286731, 1067.4289255248405, 1080.6379862746069, 1142.4573327669457, 1215.5563219424846, 1254.4919307680404, 754.8452567223706, 816.8652172391489, 826.5990994367727, 851.1743608699552, 860.6213266162869, 4967.850964470999, 4982.501749825061, 5007.273945277987, 5026.673266237683, 5119.4129819756, 4752.954350043055, 5409.863928575043, 5454.754536045887, 5455.989847462112, 5533.363663178786, 4313.231575054213, 5084.415292241366, 5358.089124848785, 5433.945660543423, 5451.536619387317, 2812.931086187236, 2912.810667306618, 2916.0960102368335, 2916.6354205724283, 2932.398858234057, 4606.650608189847, 5651.630950596949, 5756.769729210785, 5767.711614419262, 5797.768089276382, 5342.358916929993, 6008.939910600925, 6052.455279024909, 6058.575899687068, 6063.181470501514, 5094.821681241138, 5506.834794956158, 6004.309504406961, 6124.7739060048425, 6134.0, 5636.0, 5658.462446067617, 5858.531317494675, 5903.645781253139, 5909.320428150818, 5780.132980052857, 5792.652440853562, 5794.348610645946, 5798.391168927371, 5837.883242335214, 5663.461971112738, 5927.911081333869, 6009.558181962416, 6113.759707355109, 6137.723802428991, 3308.818015009101, 3832.9233415332096, 3911.9804400977814, 3915.3735402335487, 3918.941215881821, 4626.236670949255, 5162.0, 5351.973240133774, 5882.794102206402, 5932.79235226765, 5496.1755736639825, 5581.888362232814, 5695.544356451391, 5735.799247026334, 5755.539556835527, 5245.6852588844795, 5634.74643641029, 5664.405237450173, 5684.857878553069, 5691.658500489983, 5238.240455133908, 5289.841304760864, 5491.121420572689, 5557.72211389437, 5613.287947176151, 4660.580547750718, 5337.919931200958, 5547.916781248366, 5594.8810237953385, 5633.887322253614, 4673.532646735299, 4883.926741098958, 5497.299808663535, 5522.3649280333275, 5530.340897730584], "value": 4054.7967707951766, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.1437532462343, 751.7707099334702, 760.1106705154968, 770.7032792374933, 785.4972817396864, 15958.202089895502, 16625.251863666133, 16677.83322166778, 16682.332706691734, 16694.415695450647, 14947.701045979074, 15032.473863414783, 15063.924680376611, 15089.471868484605, 15094.849051509482, 650.7397041183517, 671.0, 823.234392015424, 850.2177996243471, 850.2560259772699, 679.4937771360344, 721.9602921839297, 814.9714759983402, 842.2125312832491, 855.482433127958, 889.4707648948865, 890.2166093837446, 892.6474042753109, 893.2496702769672, 893.9418937769057, 886.5567216391804, 886.592167602897, 888.1030159538848, 888.3248730964481, 889.0886840988006, 510.5532658923442, 528.1022262154353, 638.73811737188, 654.8297442664893, 660.7720336483906, 11963.521459141624, 14255.215963122024, 14380.0, 14397.496087636988, 14484.0, 12362.876371236262, 12644.241345519298, 12833.0, 12844.678883027998, 12973.221606703533, 10836.620718274822, 11426.71433214168, 11450.370229637367, 11946.880531194663, 12283.877161228364, 3668.706503479715, 3703.703703703697, 3722.9069273268115, 3784.3188226119287, 3798.601146879593, 83379.0, 87236.1276387228, 87760.0, 88718.0, 89322.0, 8331.16688331162, 8480.491170529787, 8511.872321915176, 8949.149830766068, 8998.775030624287, 15495.992760470534, 15607.297671604805, 15632.531024069296, 19703.40889773309, 19771.110300036533, 7580.3556697680215, 7750.961245193738, 7809.453338266267, 7846.607669616496, 8000.119986801447, 3557.5019497270405, 4043.7978101094827, 4173.311403618369, 4229.238737027365, 4306.074194048295, 3474.3919814032442, 3573.5890372607482, 3580.7135429165605, 3581.5702115746276, 3587.157018100744, 639.4660458517097, 641.6118248459708, 642.1459458919626, 649.8083065495596, 666.1074160624786, 553.0, 600.4115966352998, 617.4967401567708, 672.2638710611873, 679.1714108787272, 7774.1837107104075, 7879.724209652634, 8792.120787921156, 9149.954250228706, 9453.810923781624, 553.518438958101, 610.6122612141322, 628.7642134199675, 628.9842753931189, 667.4160109903834, 4243.553465385998, 4424.46906371231, 4440.178566965122, 4484.260097083945, 4508.594226519629, 73079.53840923152, 74764.87852682214, 98386.01613983912, 113000.0, 113036.43481782539, 64991.35008649948, 65990.0, 83067.0, 100339.0, 103763.48118259512, 92753.0, 93505.0, 93854.0, 94683.0531694688, 94897.52551237338, 8675.132486751274, 8702.1297870215, 8740.694075707319, 8741.91258087411, 8822.2060014599, 45200.77399613112, 51305.0, 51954.22068669049, 61399.0, 61439.0, 70885.0, 72131.63934180296, 72475.0, 76652.61673691595, 78708.60645696965, 12337.753244935231, 12396.435440823276, 12397.62807115788, 12606.747865042831, 12754.68113297175, 13375.665608359868, 13700.588982330546, 14095.788563171356, 14297.070690405091, 14852.183129927958, 12144.635660930186, 12489.500419983098, 12774.23354598727, 12795.976321894412, 13413.859821915246, 12278.938605306916, 13249.602511924657, 13331.800022999856, 13484.325783710972, 13685.178889266675, 11960.282383057045, 12960.546380876624, 12979.935100324816, 13215.66960825987, 13368.930485561326, 10132.898671013196, 10843.132549396187, 11123.499442524944, 11307.321560706385, 11440.542378305108, 7925.643346049326, 9357.57890894925, 10339.896601033894, 10587.0, 11592.188546801643], "value": 7171.526500062311, "unit": "score"}], "name": "dromaeo_css", "value": 7000.823158298263, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 169.0, 129.0, 114.0, 114.0, 115.0, 115.0, 113.0, 114.0, 115.0], "value": 125.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 112.0, 113.0, 147.0, 114.0, 98.0, 132.0, 142.0, 157.0, 122.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 195.0, 260.0, 202.0, 181.0, 183.0, 182.0, 183.0, 185.0], "value": 193.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 59.0, 87.0, 64.0, 59.0, 60.0, 64.0, 99.0, 71.0], "value": 70.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 96.0, 69.0, 68.0, 69.0, 89.0, 68.0, 69.0, 89.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 90.0, 86.0, 97.0, 130.0, 114.0, 86.0, 89.0, 86.0], "value": 95.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 98.0, 98.0, 97.0, 97.0, 97.0, 97.0, 96.0, 97.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 82.0, 79.0, 84.0, 81.0, 84.0, 86.0, 84.0, 80.0], "value": 82.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 57.0, 59.0, 58.0, 59.0, 59.0, 59.0, 56.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 81.0, 74.0, 73.0, 71.0, 68.0, 69.0, 70.0, 69.0, 73.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 81.0, 86.0, 84.0, 83.0, 85.0, 83.0, 81.0, 90.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 145.0, 141.0, 148.0, 164.0, 145.0, 142.0, 211.0, 173.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 222.0, 232.0, 167.0, 167.0, 165.0, 144.0, 225.0, 220.0, 171.0], "value": 187.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 67.0, 67.0, 65.0, 66.0, 67.0, 65.0, 65.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1493.9, "alertThreshold": 2.0}]} 04:16:26 INFO - Return code: 0 04:16:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:16:26 ERROR - # TBPL SUCCESS # 04:16:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:16:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:16:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:16:26 INFO - 2016-11-16 04:16:26.610 screenresolution[1660:10365] starting screenresolution argv=screenresolution get 04:16:26 INFO - 2016-11-16 04:16:26.614 screenresolution[1660:10365] Display 0: 1600x1200x32@60 04:16:26 INFO - 2016-11-16 04:16:26.620 screenresolution[1661:10368] starting screenresolution argv=screenresolution list 04:16:26 INFO - Available Modes on Display 0 04:16:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:16:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:16:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:16:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:16:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:16:26 INFO - Intel Iris: 04:16:26 INFO - Chipset Model: Intel Iris 04:16:26 INFO - Type: GPU 04:16:26 INFO - Bus: Built-In 04:16:26 INFO - VRAM (Dynamic, Max): 1536 MB 04:16:26 INFO - Vendor: Intel (0x8086) 04:16:26 INFO - Device ID: 0x0a2e 04:16:26 INFO - Revision ID: 0x0009 04:16:26 INFO - Displays: 04:16:26 INFO - Display: 04:16:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:16:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:16:26 INFO - Display Serial Number: 1600x1200 60 04:16:26 INFO - Main Display: Yes 04:16:26 INFO - Mirror: Off 04:16:26 INFO - Online: Yes 04:16:26 INFO - Rotation: Supported 04:16:26 INFO - Return code: 0 04:16:26 INFO - Running post-action listener: _resource_record_post_action 04:16:26 INFO - [mozharness: 2016-11-16 12:16:26.858696Z] Finished run-tests step (success) 04:16:26 INFO - Running post-run listener: _resource_record_post_run 04:16:26 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 33524736; Write bytes: 655554048; Read time: 315; Write time: 2201 04:16:26 INFO - TinderboxPrint: CPU usage
53.5% 04:16:26 INFO - TinderboxPrint: I/O read bytes / time
33,524,736 / 315 04:16:26 INFO - TinderboxPrint: I/O write bytes / time
655,554,048 / 2,201 04:16:26 INFO - TinderboxPrint: CPU idle
2,256.1 (46.5%) 04:16:26 INFO - TinderboxPrint: CPU system
974.4 (20.1%) 04:16:26 INFO - TinderboxPrint: CPU user
1,619.3 (33.4%) 04:16:26 INFO - TinderboxPrint: Swap in / out
382,541,824 / 0 04:16:26 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354175488; Write bytes: 355550720; Read time: 27594; Write time: 749 04:16:26 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 29092864; Write bytes: 298618880; Read time: 279; Write time: 1448 04:16:27 INFO - Running post-run listener: _upload_blobber_files 04:16:27 INFO - Blob upload gear active. 04:16:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:16:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:16:27 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'] 04:16:27 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 04:16:27 INFO - (blobuploader) - INFO - Open directory for files ... 04:16:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:16:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:16:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:16:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:16:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:16:28 INFO - (blobuploader) - INFO - Done attempting. 04:16:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:16:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:16:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:16:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:16:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:16:29 INFO - (blobuploader) - INFO - Done attempting. 04:16:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:16:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:16:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:16:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:16:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:16:31 INFO - (blobuploader) - INFO - Done attempting. 04:16:31 INFO - (blobuploader) - INFO - Iteration through files over. 04:16:31 INFO - Return code: 0 04:16:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:16:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:16:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3441d511b7022e9a8e73d9e5e1cdb94c2a4969989d2ffe6cc902f68ec8c27d5045e77628ea6d09df8811015574542c438712112725374523d45424eefcd0b617", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8bf591fc928076fd049a6d84648628b6e18f59548ef33d152a9d32327e8993e58fe0aa13ac038abb9fd3408b214ec4a1c92e6a84b1693608b81da7e05bf5ec63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdf84c33ad20aa80823e060cb55aeca7275f4e4063d6ca5dabbf1f541e824fd7637d325c52d9b92d716ba60d3fdc3208c383ca1a053dcbf858a48bc6b2a2cbfe"} 04:16:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:16:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:16:31 INFO - Contents: 04:16:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3441d511b7022e9a8e73d9e5e1cdb94c2a4969989d2ffe6cc902f68ec8c27d5045e77628ea6d09df8811015574542c438712112725374523d45424eefcd0b617", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8bf591fc928076fd049a6d84648628b6e18f59548ef33d152a9d32327e8993e58fe0aa13ac038abb9fd3408b214ec4a1c92e6a84b1693608b81da7e05bf5ec63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdf84c33ad20aa80823e060cb55aeca7275f4e4063d6ca5dabbf1f541e824fd7637d325c52d9b92d716ba60d3fdc3208c383ca1a053dcbf858a48bc6b2a2cbfe"} 04:16:31 INFO - Running post-run listener: copy_logs_to_upload_dir 04:16:31 INFO - Copying logs to upload dir... 04:16:31 INFO - mkdir: /builds/slave/test/build/upload/logs 04:16:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.804475 ========= master_lag: 1.85 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-16 04:16:33.662595) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 04:16:33.665901) ========= 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.r6b5go3L1P/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.jkNDyCvY9a/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3441d511b7022e9a8e73d9e5e1cdb94c2a4969989d2ffe6cc902f68ec8c27d5045e77628ea6d09df8811015574542c438712112725374523d45424eefcd0b617", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8bf591fc928076fd049a6d84648628b6e18f59548ef33d152a9d32327e8993e58fe0aa13ac038abb9fd3408b214ec4a1c92e6a84b1693608b81da7e05bf5ec63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdf84c33ad20aa80823e060cb55aeca7275f4e4063d6ca5dabbf1f541e824fd7637d325c52d9b92d716ba60d3fdc3208c383ca1a053dcbf858a48bc6b2a2cbfe"} build_url:https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010902 build_url: 'https://queue.taskcluster.net/v1/task/HdKU_uFGQpqYf1o728rucw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3441d511b7022e9a8e73d9e5e1cdb94c2a4969989d2ffe6cc902f68ec8c27d5045e77628ea6d09df8811015574542c438712112725374523d45424eefcd0b617", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8bf591fc928076fd049a6d84648628b6e18f59548ef33d152a9d32327e8993e58fe0aa13ac038abb9fd3408b214ec4a1c92e6a84b1693608b81da7e05bf5ec63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdf84c33ad20aa80823e060cb55aeca7275f4e4063d6ca5dabbf1f541e824fd7637d325c52d9b92d716ba60d3fdc3208c383ca1a053dcbf858a48bc6b2a2cbfe"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 04:16:33.698926) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 04:16:33.699210) ========= 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.r6b5go3L1P/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.jkNDyCvY9a/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.005134 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 04:16:33.746953) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 04:16:33.747230) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 04:16:33.747513) ========= ========= Total master_lag: 1.98 =========