builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0108 starttime: 1482940311.18 results: success (0) buildid: 20161228064836 builduid: 882cc1a132f3490abf1609a8b7aca608 revision: 7f4072c726a0f6321320baceb534e288ce8b1e33 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 07:51:51.179053) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 07:51:51.179543) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 07:51:51.179850) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 07:51:51.444985) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 07:51:51.445334) ========= 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.IRiVE7ay0a/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.KmKEYRK9I2/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-28 07:51:51-- 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% 8.25M=0.001s 2016-12-28 07:51:51 (8.25 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.507467 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 07:51:51.990948) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 07:51:51.991337) ========= 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.IRiVE7ay0a/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.KmKEYRK9I2/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.019903 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 07:51:52.061083) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 07:51:52.061756) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7f4072c726a0f6321320baceb534e288ce8b1e33 --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 7f4072c726a0f6321320baceb534e288ce8b1e33 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IRiVE7ay0a/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.KmKEYRK9I2/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-28 07:51:52,131 truncating revision to first 12 chars 2016-12-28 07:51:52,131 Setting DEBUG logging. 2016-12-28 07:51:52,131 attempt 1/10 2016-12-28 07:51:52,131 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7f4072c726a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-28 07:51:53,245 unpacking tar archive at: mozilla-aurora-7f4072c726a0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.371920 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 07:51:53.463696) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 07:51:53.464131) ========= script_repo_revision: 7f4072c726a0f6321320baceb534e288ce8b1e33 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 07:51:53.464593) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 07:51:53.464872) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 07:51:53.489114) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-28 07:51:53.489528) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IRiVE7ay0a/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.KmKEYRK9I2/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 07:51:53 INFO - MultiFileLogger online at 20161228 07:51:53 in /builds/slave/test 07:51:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:51:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:51:53 INFO - {'append_to_log': False, 07:51:53 INFO - 'base_work_dir': '/builds/slave/test', 07:51:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:51:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:51:53 INFO - 'branch': 'Mozilla-Aurora', 07:51:53 INFO - 'buildbot_json_path': 'buildprops.json', 07:51:53 INFO - 'config_files': ('talos/mac_config.py',), 07:51:53 INFO - 'default_actions': ('clobber', 07:51:53 INFO - 'read-buildbot-config', 07:51:53 INFO - 'download-and-extract', 07:51:53 INFO - 'populate-webroot', 07:51:53 INFO - 'create-virtualenv', 07:51:53 INFO - 'install', 07:51:53 INFO - 'run-tests'), 07:51:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:51:53 INFO - 'download_minidump_stackwalk': True, 07:51:53 INFO - 'download_symbols': 'ondemand', 07:51:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:51:53 INFO - 'tooltool.py': '/tools/tooltool.py', 07:51:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:51:53 INFO - '/tools/misc-python/virtualenv.py')}, 07:51:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:51:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:51:53 INFO - 'installer_path': 'installer.exe', 07:51:53 INFO - 'log_level': 'info', 07:51:53 INFO - 'log_name': 'talos', 07:51:53 INFO - 'log_to_console': True, 07:51:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:51:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:51:53 INFO - 'opt_config_files': (), 07:51:53 INFO - 'pip_index': False, 07:51:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:51:53 INFO - 'cmd': ('bash', 07:51:53 INFO - '-c', 07:51:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:51:53 INFO - 'enabled': True, 07:51:53 INFO - 'halt_on_failure': False, 07:51:53 INFO - 'name': 'check_screen_resolution'},), 07:51:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:51:53 INFO - 'cmd': ('bash', 07:51:53 INFO - '-c', 07:51:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:51:53 INFO - 'enabled': True, 07:51:53 INFO - 'halt_on_failure': False, 07:51:53 INFO - 'name': 'check_screen_resolution'},), 07:51:53 INFO - 'run_cmd_checks_enabled': True, 07:51:53 INFO - 'sps_profile': False, 07:51:53 INFO - 'sps_profile_interval': 0, 07:51:53 INFO - 'suite': 'dromaeojs-e10s', 07:51:53 INFO - 'system_bits': '32', 07:51:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:51:53 INFO - 'title': 't-yosemite-r7-0108', 07:51:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:51:53 INFO - 'use_talos_json': True, 07:51:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:51:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:51:53 INFO - 'work_dir': 'build'} 07:51:53 INFO - [mozharness: 2016-12-28 15:51:53.681947Z] Running clobber step. 07:51:53 INFO - Running pre-action listener: _resource_record_pre_action 07:51:53 INFO - Running main action method: clobber 07:51:53 INFO - rmtree: /builds/slave/test/build 07:51:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:51:55 INFO - Running post-action listener: _resource_record_post_action 07:51:55 INFO - [mozharness: 2016-12-28 15:51:55.730597Z] Finished clobber step (success) 07:51:55 INFO - [mozharness: 2016-12-28 15:51:55.730722Z] Running read-buildbot-config step. 07:51:55 INFO - Running pre-action listener: _resource_record_pre_action 07:51:55 INFO - Running main action method: read_buildbot_config 07:51:55 INFO - Using buildbot properties: 07:51:55 INFO - { 07:51:55 INFO - "project": "", 07:51:55 INFO - "product": "firefox", 07:51:55 INFO - "script_repo_revision": "production", 07:51:55 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 07:51:55 INFO - "repository": "", 07:51:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 07:51:55 INFO - "buildid": "20161228064836", 07:51:55 INFO - "pgo_build": "False", 07:51:55 INFO - "basedir": "/builds/slave/test", 07:51:55 INFO - "buildnumber": 54, 07:51:55 INFO - "slavename": "t-yosemite-r7-0108", 07:51:55 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:51:55 INFO - "platform": "yosemite_r7", 07:51:55 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:51:55 INFO - "branch": "mozilla-aurora", 07:51:55 INFO - "repo_path": "releases/mozilla-aurora", 07:51:55 INFO - "revision": "7f4072c726a0f6321320baceb534e288ce8b1e33", 07:51:55 INFO - "stage_platform": "macosx64", 07:51:55 INFO - "builduid": "882cc1a132f3490abf1609a8b7aca608", 07:51:55 INFO - "slavebuilddir": "test" 07:51:55 INFO - } 07:51:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 07:51:55 INFO - Running post-action listener: _resource_record_post_action 07:51:55 INFO - [mozharness: 2016-12-28 15:51:55.733943Z] Finished read-buildbot-config step (success) 07:51:55 INFO - [mozharness: 2016-12-28 15:51:55.734078Z] Running download-and-extract step. 07:51:55 INFO - Running pre-action listener: _resource_record_pre_action 07:51:55 INFO - Running main action method: download_and_extract 07:51:55 INFO - mkdir: /builds/slave/test/build/tests 07:51:55 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')]} 07:51:55 INFO - trying https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 07:51:55 INFO - Downloading https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/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 07:51:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/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 07:51:56 INFO - Downloaded 1199 bytes. 07:51:56 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:51:56 INFO - Using the following test package requirements: 07:51:56 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:51:56 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:51:56 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 07:51:56 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:51:56 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 07:51:56 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:51:56 INFO - u'jsshell-mac.zip'], 07:51:56 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:51:56 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 07:51:56 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:51:56 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:51:56 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 07:51:56 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:51:56 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 07:51:56 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:51:56 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 07:51:56 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:51:56 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 07:51:56 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:51:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:51:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:51:56 INFO - Fetch https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:51:57 INFO - Http code: 200 07:51:57 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 07:51:57 INFO - x-amz-cf-id: argIuyiXMWE1TRTa-8WfKqg4dHHMKosZ99F3QBfNz7sKWyj1aWHYvw== 07:51:57 INFO - x-amz-version-id: vXQNrC7zwyaahMzY9F7Rknyaqvfp9FU2 07:51:57 INFO - Expected file size: 16268320 07:51:57 INFO - Obtained file size: 16268320 07:52:04 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 07:52:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:52:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:52:04 INFO - Fetch https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:52:05 INFO - Http code: 200 07:52:05 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 07:52:05 INFO - x-amz-cf-id: -keUwULYKgoFJegb8ySNFeHUbJtedFZQeqBWYdIPjJCZTHVqA0rNow== 07:52:05 INFO - x-amz-version-id: vXQNrC7zwyaahMzY9F7Rknyaqvfp9FU2 07:52:05 INFO - Expected file size: 16268320 07:52:05 INFO - Obtained file size: 16268320 07:52:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 07:52:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:52:11 INFO - Fetch https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 07:52:12 INFO - Http code: 200 07:52:12 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 07:52:12 INFO - x-amz-cf-id: BpC2PwF1fUuTiqBsZRtcR2zeGHfqGSXtJoHfqm4e3s45XzFuVTReVg== 07:52:12 INFO - x-amz-version-id: KGDfx3oLl.KcqVrIvhNJyT3AaavGN7H5 07:52:12 INFO - Expected file size: 14409038 07:52:12 INFO - Obtained file size: 14409038 07:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:12 INFO - trying https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:52:12 INFO - Downloading https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 07:52:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/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 07:52:16 INFO - Downloaded 109821458 bytes. 07:52:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:52:16 INFO - mkdir: /builds/slave/test/properties 07:52:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:52:16 INFO - Writing to file /builds/slave/test/properties/build_url 07:52:16 INFO - Contents: 07:52:16 INFO - build_url:https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:52:19 INFO - Running post-action listener: _resource_record_post_action 07:52:19 INFO - Running post-action listener: set_extra_try_arguments 07:52:19 INFO - [mozharness: 2016-12-28 15:52:19.231429Z] Finished download-and-extract step (success) 07:52:19 INFO - [mozharness: 2016-12-28 15:52:19.231546Z] Running populate-webroot step. 07:52:19 INFO - Running pre-action listener: _resource_record_pre_action 07:52:19 INFO - Running main action method: populate_webroot 07:52:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:52:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:52:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:52:19 INFO - '--tppagecycles', 07:52:19 INFO - '7'], 07:52:19 INFO - 'tests': ['tsvgm']}}, 07:52:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:52:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:52:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:52:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:52:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:52:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:52:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:19 INFO - 'talos_options': ['--disable-e10s'], 07:52:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:52:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:52:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:19 INFO - 'talos_options': ['--disable-e10s'], 07:52:19 INFO - 'tests': ['damp', 'tps']}, 07:52:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:19 INFO - 'tests': ['damp', 'tps']}, 07:52:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:52:19 INFO - 'tests': ['dromaeo_dom']}, 07:52:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:52:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:52:19 INFO - 'tests': ['basic_compositor_video']}, 07:52:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:52:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:52:19 INFO - 'tests': ['a11yr', 07:52:19 INFO - 'ts_paint', 07:52:19 INFO - 'tpaint', 07:52:19 INFO - 'sessionrestore', 07:52:19 INFO - 'sessionrestore_no_auto_restore', 07:52:19 INFO - 'tabpaint']}, 07:52:19 INFO - 'other-e10s': {'tests': ['a11yr', 07:52:19 INFO - 'ts_paint', 07:52:19 INFO - 'tpaint', 07:52:19 INFO - 'sessionrestore', 07:52:19 INFO - 'sessionrestore_no_auto_restore', 07:52:19 INFO - 'tabpaint']}, 07:52:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:52:19 INFO - 'ts_paint', 07:52:19 INFO - 'tpaint', 07:52:19 INFO - 'sessionrestore', 07:52:19 INFO - 'sessionrestore_no_auto_restore', 07:52:19 INFO - 'tabpaint']}, 07:52:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:52:19 INFO - 'ts_paint', 07:52:19 INFO - 'tpaint', 07:52:19 INFO - 'sessionrestore', 07:52:19 INFO - 'sessionrestore_no_auto_restore', 07:52:19 INFO - 'tabpaint']}, 07:52:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:52:19 INFO - 'tests': ['a11yr', 07:52:19 INFO - 'ts_paint', 07:52:19 INFO - 'tpaint', 07:52:19 INFO - 'sessionrestore', 07:52:19 INFO - 'sessionrestore_no_auto_restore', 07:52:19 INFO - 'tabpaint']}, 07:52:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:52:19 INFO - 'tests': ['a11yr', 07:52:19 INFO - 'ts_paint', 07:52:19 INFO - 'tpaint', 07:52:19 INFO - 'sessionrestore', 07:52:19 INFO - 'sessionrestore_no_auto_restore', 07:52:19 INFO - 'tabpaint']}, 07:52:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:52:19 INFO - 'tests': ['tsvgx', 07:52:19 INFO - 'tsvgr_opacity', 07:52:19 INFO - 'tart', 07:52:19 INFO - 'tscrollx', 07:52:19 INFO - 'cart']}, 07:52:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:52:19 INFO - 'tsvgr_opacity', 07:52:19 INFO - 'tart', 07:52:19 INFO - 'tscrollx', 07:52:19 INFO - 'cart']}, 07:52:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:19 INFO - 'talos_options': ['--disable-e10s'], 07:52:19 INFO - 'tests': ['tp5o']}, 07:52:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:19 INFO - 'tests': ['tp5o']}, 07:52:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:19 INFO - 'talos_options': ['--disable-e10s', 07:52:19 INFO - '--xperf_path', 07:52:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:52:19 INFO - 'tests': ['tp5n']}, 07:52:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:19 INFO - 'talos_options': ['--xperf_path', 07:52:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:52:19 INFO - 'tests': ['tp5n']}}, 07:52:19 INFO - 'talos.zip': {'path': '', 07:52:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:52:19 INFO - Running post-action listener: _resource_record_post_action 07:52:19 INFO - [mozharness: 2016-12-28 15:52:19.250412Z] Finished populate-webroot step (success) 07:52:19 INFO - [mozharness: 2016-12-28 15:52:19.250498Z] Running create-virtualenv step. 07:52:19 INFO - Running pre-action listener: _resource_record_pre_action 07:52:19 INFO - Running main action method: create_virtualenv 07:52:19 INFO - Creating virtualenv /builds/slave/test/build/venv 07:52:19 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 07:52:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:52:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:52:19 INFO - Using real prefix '/tools/python27' 07:52:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:52:20 INFO - Installing distribute.............................................................................................................................................................................................done. 07:52:22 INFO - Installing pip.................done. 07:52:22 INFO - Return code: 0 07:52:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:52:23 INFO - Reading from file tmpfile_stdout 07:52:23 INFO - Output received: 07:52:23 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) 07:52:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:52:23 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')]} 07:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:23 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')]} 07:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:23 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 0x108281490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab8156bcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108095d30>, '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 0x107efbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10808db20>, '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.IRiVE7ay0a/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.KmKEYRK9I2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 07:52:23 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 07:52:23 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 07:52:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRiVE7ay0a/Render', 07:52:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:23 INFO - 'HOME': '/Users/cltbld', 07:52:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:23 INFO - 'LOGNAME': 'cltbld', 07:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:23 INFO - 'MOZ_NO_REMOTE': '1', 07:52:23 INFO - 'NO_EM_RESTART': '1', 07:52:23 INFO - 'PAGER': '/bin/cat', 07:52:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:23 INFO - 'PWD': '/builds/slave/test', 07:52:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:23 INFO - 'SHELL': '/bin/bash', 07:52:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KmKEYRK9I2/Listeners', 07:52:23 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 07:52:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:23 INFO - 'USER': 'cltbld', 07:52:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:23 INFO - 'XPC_FLAGS': '0x0', 07:52:23 INFO - 'XPC_SERVICE_NAME': '0', 07:52:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:23 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 07:52:23 INFO - Cleaning up... 07:52:23 INFO - Return code: 0 07:52:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:52:23 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')]} 07:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:23 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')]} 07:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:23 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 0x108281490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab8156bcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108095d30>, '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 0x107efbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10808db20>, '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.IRiVE7ay0a/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.KmKEYRK9I2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 07:52:23 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 07:52:23 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 07:52:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRiVE7ay0a/Render', 07:52:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:23 INFO - 'HOME': '/Users/cltbld', 07:52:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:23 INFO - 'LOGNAME': 'cltbld', 07:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:23 INFO - 'MOZ_NO_REMOTE': '1', 07:52:23 INFO - 'NO_EM_RESTART': '1', 07:52:23 INFO - 'PAGER': '/bin/cat', 07:52:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:23 INFO - 'PWD': '/builds/slave/test', 07:52:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:23 INFO - 'SHELL': '/bin/bash', 07:52:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KmKEYRK9I2/Listeners', 07:52:23 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 07:52:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:23 INFO - 'USER': 'cltbld', 07:52:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:23 INFO - 'XPC_FLAGS': '0x0', 07:52:23 INFO - 'XPC_SERVICE_NAME': '0', 07:52:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:23 INFO - Downloading/unpacking psutil>=3.1.1 07:52:23 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 07:52:23 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 07:52:23 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 07:52:23 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 07:52:23 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 07:52:23 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 07:52:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:52:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:52:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:52:27 INFO - Installing collected packages: psutil 07:52:27 INFO - Running setup.py install for psutil 07:52:28 INFO - building 'psutil._psutil_osx' extension 07:52:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:52:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:52:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:52:28 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 07:52:28 INFO - building 'psutil._psutil_posix' extension 07:52:28 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 07:52:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:52:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:52:28 INFO - ^ 07:52:28 INFO - 1 warning generated. 07:52:28 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 07:52:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:52:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:52:28 INFO - Successfully installed psutil 07:52:28 INFO - Cleaning up... 07:52:28 INFO - Return code: 0 07:52:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:52:28 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')]} 07:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:28 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')]} 07:52:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:28 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 0x108281490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab8156bcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108095d30>, '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 0x107efbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10808db20>, '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.IRiVE7ay0a/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.KmKEYRK9I2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 07:52:28 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 07:52:28 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 07:52:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRiVE7ay0a/Render', 07:52:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:28 INFO - 'HOME': '/Users/cltbld', 07:52:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:28 INFO - 'LOGNAME': 'cltbld', 07:52:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:28 INFO - 'MOZ_NO_REMOTE': '1', 07:52:28 INFO - 'NO_EM_RESTART': '1', 07:52:28 INFO - 'PAGER': '/bin/cat', 07:52:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:28 INFO - 'PWD': '/builds/slave/test', 07:52:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:28 INFO - 'SHELL': '/bin/bash', 07:52:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KmKEYRK9I2/Listeners', 07:52:28 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 07:52:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:28 INFO - 'USER': 'cltbld', 07:52:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:28 INFO - 'XPC_FLAGS': '0x0', 07:52:28 INFO - 'XPC_SERVICE_NAME': '0', 07:52:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:52:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:52:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:52:31 INFO - Installing collected packages: mozsystemmonitor 07:52:31 INFO - Running setup.py install for mozsystemmonitor 07:52:31 INFO - Successfully installed mozsystemmonitor 07:52:31 INFO - Cleaning up... 07:52:32 INFO - Return code: 0 07:52:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108281490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab8156bcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108095d30>, '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 0x107efbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10808db20>, '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.IRiVE7ay0a/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.KmKEYRK9I2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 07:52:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:52:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:52:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRiVE7ay0a/Render', 07:52:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:32 INFO - 'HOME': '/Users/cltbld', 07:52:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:32 INFO - 'LOGNAME': 'cltbld', 07:52:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:32 INFO - 'MOZ_NO_REMOTE': '1', 07:52:32 INFO - 'NO_EM_RESTART': '1', 07:52:32 INFO - 'PAGER': '/bin/cat', 07:52:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:32 INFO - 'PWD': '/builds/slave/test', 07:52:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:32 INFO - 'SHELL': '/bin/bash', 07:52:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KmKEYRK9I2/Listeners', 07:52:32 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 07:52:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:32 INFO - 'USER': 'cltbld', 07:52:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:32 INFO - 'XPC_FLAGS': '0x0', 07:52:32 INFO - 'XPC_SERVICE_NAME': '0', 07:52:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:32 INFO - Downloading/unpacking jsonschema==2.5.1 07:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:52:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:52:34 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 07:52:34 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 07:52:34 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 07:52:34 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 07:52:34 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 07:52:34 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 07:52:35 INFO - Downloading functools32-3.2.3-2.tar.gz 07:52:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:52:35 INFO - warning: no files found matching '*.txt' 07:52:35 INFO - no previously-included directories found matching 'build' 07:52:35 INFO - no previously-included directories found matching 'dist' 07:52:35 INFO - no previously-included directories found matching '.git*' 07:52:35 INFO - Installing collected packages: jsonschema, functools32 07:52:35 INFO - Running setup.py install for functools32 07:52:35 INFO - warning: no files found matching '*.txt' 07:52:35 INFO - no previously-included directories found matching 'build' 07:52:35 INFO - no previously-included directories found matching 'dist' 07:52:35 INFO - no previously-included directories found matching '.git*' 07:52:35 INFO - Successfully installed jsonschema functools32 07:52:35 INFO - Cleaning up... 07:52:35 INFO - Return code: 0 07:52:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:52:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:35 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 0x108281490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab8156bcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108095d30>, '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 0x107efbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10808db20>, '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.IRiVE7ay0a/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.KmKEYRK9I2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 07:52:35 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 07:52:35 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 07:52:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRiVE7ay0a/Render', 07:52:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:35 INFO - 'HOME': '/Users/cltbld', 07:52:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:35 INFO - 'LOGNAME': 'cltbld', 07:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:35 INFO - 'MOZ_NO_REMOTE': '1', 07:52:35 INFO - 'NO_EM_RESTART': '1', 07:52:35 INFO - 'PAGER': '/bin/cat', 07:52:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:35 INFO - 'PWD': '/builds/slave/test', 07:52:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:35 INFO - 'SHELL': '/bin/bash', 07:52:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KmKEYRK9I2/Listeners', 07:52:35 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 07:52:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:35 INFO - 'USER': 'cltbld', 07:52:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:35 INFO - 'XPC_FLAGS': '0x0', 07:52:35 INFO - 'XPC_SERVICE_NAME': '0', 07:52:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:52:35 INFO - Cleaning up... 07:52:36 INFO - Return code: 0 07:52:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:52:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108281490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab8156bcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108095d30>, '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 0x107efbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10808db20>, '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.IRiVE7ay0a/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.KmKEYRK9I2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 07:52:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:52:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:52:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRiVE7ay0a/Render', 07:52:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:36 INFO - 'HOME': '/Users/cltbld', 07:52:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:36 INFO - 'LOGNAME': 'cltbld', 07:52:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:36 INFO - 'MOZ_NO_REMOTE': '1', 07:52:36 INFO - 'NO_EM_RESTART': '1', 07:52:36 INFO - 'PAGER': '/bin/cat', 07:52:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:36 INFO - 'PWD': '/builds/slave/test', 07:52:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:36 INFO - 'SHELL': '/bin/bash', 07:52:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KmKEYRK9I2/Listeners', 07:52:36 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 07:52:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:36 INFO - 'USER': 'cltbld', 07:52:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:36 INFO - 'XPC_FLAGS': '0x0', 07:52:36 INFO - 'XPC_SERVICE_NAME': '0', 07:52:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:36 INFO - Downloading/unpacking blobuploader==1.2.4 07:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:38 INFO - Downloading blobuploader-1.2.4.tar.gz 07:52:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:52:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:52:38 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 07:52:38 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 07:52:38 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 07:52:38 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 07:52:38 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 07:52:38 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 07:52:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:52:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:40 INFO - Downloading docopt-0.6.1.tar.gz 07:52:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:52:40 INFO - Installing collected packages: blobuploader, requests, docopt 07:52:40 INFO - Running setup.py install for blobuploader 07:52:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:52:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:52:40 INFO - Running setup.py install for requests 07:52:40 INFO - Running setup.py install for docopt 07:52:40 INFO - Successfully installed blobuploader requests docopt 07:52:40 INFO - Cleaning up... 07:52:41 INFO - Return code: 0 07:52:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108281490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab8156bcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108095d30>, '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 0x107efbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10808db20>, '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.IRiVE7ay0a/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.KmKEYRK9I2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 07:52:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:52:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:52:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRiVE7ay0a/Render', 07:52:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:41 INFO - 'HOME': '/Users/cltbld', 07:52:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:41 INFO - 'LOGNAME': 'cltbld', 07:52:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:41 INFO - 'MOZ_NO_REMOTE': '1', 07:52:41 INFO - 'NO_EM_RESTART': '1', 07:52:41 INFO - 'PAGER': '/bin/cat', 07:52:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:41 INFO - 'PWD': '/builds/slave/test', 07:52:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:41 INFO - 'SHELL': '/bin/bash', 07:52:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KmKEYRK9I2/Listeners', 07:52:41 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 07:52:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:41 INFO - 'USER': 'cltbld', 07:52:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:41 INFO - 'XPC_FLAGS': '0x0', 07:52:41 INFO - 'XPC_SERVICE_NAME': '0', 07:52:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:52:41 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-qre8Li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:52:41 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-UqwK4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:52:41 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-WiMFUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:52:41 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-wAMk6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:52:41 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-1MMP6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:52:41 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-qlJAIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:52:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-n8Exk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:52:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-OrYA6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:52:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-yFAhCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:52:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-f8nXYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:52:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-CpzYeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:52:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-TnZfA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:52:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-5bpLpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:52:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-p23E2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Wh3dvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:52:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Ehv2cR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:52:43 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-zAMgZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:52:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:52:43 INFO - Running setup.py install for manifestparser 07:52:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:52:43 INFO - Running setup.py install for mozcrash 07:52:43 INFO - Running setup.py install for mozdebug 07:52:43 INFO - Running setup.py install for mozdevice 07:52:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:52:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:52:43 INFO - Running setup.py install for mozfile 07:52:43 INFO - Running setup.py install for mozhttpd 07:52:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:52:44 INFO - Running setup.py install for mozinfo 07:52:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:52:44 INFO - Running setup.py install for mozInstall 07:52:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:52:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:52:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:52:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:52:44 INFO - Running setup.py install for mozleak 07:52:44 INFO - Running setup.py install for mozlog 07:52:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:52:44 INFO - Running setup.py install for moznetwork 07:52:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:52:44 INFO - Running setup.py install for mozprocess 07:52:44 INFO - Running setup.py install for mozprofile 07:52:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:52:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:52:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:52:45 INFO - Running setup.py install for mozrunner 07:52:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:52:45 INFO - Running setup.py install for mozscreenshot 07:52:45 INFO - Running setup.py install for moztest 07:52:45 INFO - Running setup.py install for mozversion 07:52:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:52:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:52:45 INFO - Cleaning up... 07:52:45 INFO - Return code: 0 07:52:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52:45 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')]} 07:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:45 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')]} 07:52:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:45 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 0x108281490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab8156bcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108095d30>, '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 0x107efbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10808db20>, '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.IRiVE7ay0a/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.KmKEYRK9I2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 07:52:45 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 07:52:45 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 07:52:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRiVE7ay0a/Render', 07:52:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:45 INFO - 'HOME': '/Users/cltbld', 07:52:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:45 INFO - 'LOGNAME': 'cltbld', 07:52:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:45 INFO - 'MOZ_NO_REMOTE': '1', 07:52:45 INFO - 'NO_EM_RESTART': '1', 07:52:45 INFO - 'PAGER': '/bin/cat', 07:52:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:45 INFO - 'PWD': '/builds/slave/test', 07:52:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:45 INFO - 'SHELL': '/bin/bash', 07:52:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KmKEYRK9I2/Listeners', 07:52:45 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 07:52:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:45 INFO - 'USER': 'cltbld', 07:52:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:45 INFO - 'XPC_FLAGS': '0x0', 07:52:45 INFO - 'XPC_SERVICE_NAME': '0', 07:52:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:52:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-RqUS2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:52:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-97eb8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:52:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-8xRSjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:52:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-K_ia4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:52:46 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)) 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:52:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-aWWQia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:52:46 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)) 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:52:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-jIq6M9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:52:46 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)) 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:52:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-vFdHay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:52:46 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)) 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:52:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-vXmFyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:52:46 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)) 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:52:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-qVWLoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:52:46 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)) 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:52:47 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-J61Lkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:52:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:52:47 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-N4gmgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:52:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:52:47 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-IDf8QH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:52:47 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)) 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:52:47 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-gzx_J6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:52:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:52:47 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Oq8DBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:52:47 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)) 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:47 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-aH1xSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:47 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)) 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:52:47 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-W9tou8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:52:47 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)) 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:52:47 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-RB1b7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:52:47 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)) 07:52:47 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)) 07:52:47 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)) 07:52:47 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)) 07:52:47 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)) 07:52:47 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)) 07:52:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:52:47 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 07:52:47 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 07:52:47 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 07:52:47 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 07:52:47 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 07:52:47 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 07:52:50 INFO - Downloading blessings-1.6.tar.gz 07:52:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:52:50 INFO - Installing collected packages: blessings 07:52:50 INFO - Running setup.py install for blessings 07:52:50 INFO - Successfully installed blessings 07:52:50 INFO - Cleaning up... 07:52:50 INFO - Return code: 0 07:52:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:52:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:52:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:52:51 INFO - Reading from file tmpfile_stdout 07:52:51 INFO - Current package versions: 07:52:51 INFO - blessings == 1.6 07:52:51 INFO - blobuploader == 1.2.4 07:52:51 INFO - docopt == 0.6.1 07:52:51 INFO - functools32 == 3.2.3-2 07:52:51 INFO - jsonschema == 2.5.1 07:52:51 INFO - manifestparser == 1.1 07:52:51 INFO - mozInstall == 1.12 07:52:51 INFO - mozcrash == 1.0 07:52:51 INFO - mozdebug == 0.1 07:52:51 INFO - mozdevice == 0.48 07:52:51 INFO - mozfile == 1.2 07:52:51 INFO - mozhttpd == 0.7 07:52:51 INFO - mozinfo == 0.9 07:52:51 INFO - mozleak == 0.1 07:52:51 INFO - mozlog == 3.4 07:52:51 INFO - moznetwork == 0.27 07:52:51 INFO - mozprocess == 0.23 07:52:51 INFO - mozprofile == 0.28 07:52:51 INFO - mozrunner == 6.13 07:52:51 INFO - mozscreenshot == 0.1 07:52:51 INFO - mozsystemmonitor == 0.3 07:52:51 INFO - moztest == 0.7 07:52:51 INFO - mozversion == 1.4 07:52:51 INFO - psutil == 3.1.1 07:52:51 INFO - requests == 1.2.3 07:52:51 INFO - wsgiref == 0.1.2 07:52:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52: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')]} 07:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52: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')]} 07:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:51 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 0x108281490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab8156bcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108095d30>, '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 0x107efbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10808db20>, '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.IRiVE7ay0a/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.KmKEYRK9I2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 07:52:51 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 07:52:51 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 07:52:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRiVE7ay0a/Render', 07:52:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:51 INFO - 'HOME': '/Users/cltbld', 07:52:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:51 INFO - 'LOGNAME': 'cltbld', 07:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:51 INFO - 'MOZ_NO_REMOTE': '1', 07:52:51 INFO - 'NO_EM_RESTART': '1', 07:52:51 INFO - 'PAGER': '/bin/cat', 07:52:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:51 INFO - 'PWD': '/builds/slave/test', 07:52:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:51 INFO - 'SHELL': '/bin/bash', 07:52:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KmKEYRK9I2/Listeners', 07:52:51 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 07:52:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:51 INFO - 'USER': 'cltbld', 07:52:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:51 INFO - 'XPC_FLAGS': '0x0', 07:52:51 INFO - 'XPC_SERVICE_NAME': '0', 07:52:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:51 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)) 07:52:51 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)) 07:52:51 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)) 07:52:51 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)) 07:52:51 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)) 07:52:51 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)) 07:52:51 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)) 07:52:51 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)) 07:52:51 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)) 07:52:51 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)) 07:52:51 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)) 07:52:51 INFO - Cleaning up... 07:52:51 INFO - Return code: 0 07:52:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:52: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')]} 07:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52: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')]} 07:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108281490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab8156bcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108095d30>, '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 0x107efbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10808db20>, '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.IRiVE7ay0a/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.KmKEYRK9I2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 07:52: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', 'jsonschema'] in /builds/slave/test/build 07:52: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 jsonschema 07:52:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRiVE7ay0a/Render', 07:52:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:51 INFO - 'HOME': '/Users/cltbld', 07:52:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:51 INFO - 'LOGNAME': 'cltbld', 07:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:51 INFO - 'MOZ_NO_REMOTE': '1', 07:52:51 INFO - 'NO_EM_RESTART': '1', 07:52:51 INFO - 'PAGER': '/bin/cat', 07:52:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:51 INFO - 'PWD': '/builds/slave/test', 07:52:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:51 INFO - 'SHELL': '/bin/bash', 07:52:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KmKEYRK9I2/Listeners', 07:52:51 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 07:52:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:51 INFO - 'USER': 'cltbld', 07:52:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:51 INFO - 'XPC_FLAGS': '0x0', 07:52:51 INFO - 'XPC_SERVICE_NAME': '0', 07:52:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:52:51 INFO - Cleaning up... 07:52:51 INFO - Return code: 0 07:52:51 INFO - Running post-action listener: _resource_record_post_action 07:52:51 INFO - Running post-action listener: _start_resource_monitoring 07:52:51 INFO - Starting resource monitoring. 07:52:51 INFO - [mozharness: 2016-12-28 15:52:51.700813Z] Finished create-virtualenv step (success) 07:52:51 INFO - [mozharness: 2016-12-28 15:52:51.701793Z] Running install step. 07:52:51 INFO - Running pre-action listener: _resource_record_pre_action 07:52:51 INFO - Running main action method: install 07:52:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:52:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:52:51 INFO - Reading from file tmpfile_stdout 07:52:51 INFO - Detecting whether we're running mozinstall >=1.0... 07:52:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:52:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:52:52 INFO - Reading from file tmpfile_stdout 07:52:52 INFO - Output received: 07:52:52 INFO - Usage: mozinstall [options] installer 07:52:52 INFO - Options: 07:52:52 INFO - -h, --help show this help message and exit 07:52:52 INFO - -d DEST, --destination=DEST 07:52:52 INFO - Directory to install application into. [default: 07:52:52 INFO - "/builds/slave/test"] 07:52:52 INFO - --app=APP Application being installed. [default: firefox] 07:52:52 INFO - mkdir: /builds/slave/test/build/application 07:52:52 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'] 07:52:52 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 07:53:23 INFO - Reading from file tmpfile_stdout 07:53:23 INFO - Output received: 07:53:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:53:23 INFO - Running post-action listener: _resource_record_post_action 07:53:23 INFO - [mozharness: 2016-12-28 15:53:23.248407Z] Finished install step (success) 07:53:23 INFO - [mozharness: 2016-12-28 15:53:23.248560Z] Running run-tests step. 07:53:23 INFO - Running pre-action listener: _resource_record_pre_action 07:53:23 INFO - Running main action method: run_tests 07:53:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:53:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:53:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:53:23 INFO - 2016-12-28 07:53:23.263 screenresolution[1906:19626] starting screenresolution argv=screenresolution get 07:53:23 INFO - 2016-12-28 07:53:23.265 screenresolution[1906:19626] Display 0: 1600x1200x32@60 07:53:23 INFO - 2016-12-28 07:53:23.271 screenresolution[1907:19628] starting screenresolution argv=screenresolution list 07:53:23 INFO - Available Modes on Display 0 07:53:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:53:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:53:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:53:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:53:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:53:23 INFO - Intel Iris: 07:53:23 INFO - Chipset Model: Intel Iris 07:53:23 INFO - Type: GPU 07:53:23 INFO - Bus: Built-In 07:53:23 INFO - VRAM (Dynamic, Max): 1536 MB 07:53:23 INFO - Vendor: Intel (0x8086) 07:53:23 INFO - Device ID: 0x0a2e 07:53:23 INFO - Revision ID: 0x0009 07:53:23 INFO - Displays: 07:53:23 INFO - Display: 07:53:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:53:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:53:23 INFO - Display Serial Number: 1600x1200 60 07:53:23 INFO - Main Display: Yes 07:53:23 INFO - Mirror: Off 07:53:23 INFO - Online: Yes 07:53:23 INFO - Rotation: Supported 07:53:23 INFO - Return code: 0 07:53:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:53:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:53:23 INFO - Python 2.7.3 07:53:23 INFO - Return code: 0 07:53:23 INFO - grabbing minidump binary from tooltool 07:53:23 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')]} 07:53:23 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 0x108095d30>, '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 0x107efbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10808db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:53:23 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 07:53:23 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 07:53:23 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 07:53:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:53:23 INFO - Return code: 0 07:53:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:53:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:53:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:53:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:53:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:53:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:53:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0108 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:53:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRiVE7ay0a/Render', 07:53:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:53:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:53:23 INFO - 'HOME': '/Users/cltbld', 07:53:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:53:23 INFO - 'LOGNAME': 'cltbld', 07:53:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:53:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:53:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:53:23 INFO - 'MOZ_NO_REMOTE': '1', 07:53:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:53:23 INFO - 'NO_EM_RESTART': '1', 07:53:23 INFO - 'PAGER': '/bin/cat', 07:53:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:53:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:53:23 INFO - 'PWD': '/builds/slave/test', 07:53:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:53:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:53:23 INFO - 'SHELL': '/bin/bash', 07:53:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KmKEYRK9I2/Listeners', 07:53:23 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 07:53:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:53:23 INFO - 'USER': 'cltbld', 07:53:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:53:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:53:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:53:23 INFO - 'XPC_FLAGS': '0x0', 07:53:23 INFO - 'XPC_SERVICE_NAME': '0', 07:53:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:53:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:53:23 INFO - mozversion application_buildid: 20161228064836 07:53:23 INFO - mozversion application_changeset: 7f4072c726a0f6321320baceb534e288ce8b1e33 07:53:23 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:53:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:53:23 INFO - mozversion application_name: Firefox 07:53:23 INFO - mozversion application_remotingname: firefox-dev 07:53:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:53:23 INFO - mozversion application_vendor: Mozilla 07:53:23 INFO - mozversion application_version: 52.0a2 07:53:23 INFO - mozversion platform_buildid: 20161228064836 07:53:23 INFO - mozversion platform_changeset: 7f4072c726a0f6321320baceb534e288ce8b1e33 07:53:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:53:23 INFO - mozversion platform_version: 52.0a2 07:53:23 INFO - using testdate: 1482940403 07:53:23 INFO - actual date: 1482940403 07:53:23 INFO - starting webserver on 'localhost:56528' 07:53:23 INFO - SUITE-START | Running 2 tests 07:53:23 INFO - TEST-START | dromaeo_css 07:53:23 INFO - operating with platform_type : mac_ 07:53:23 INFO - Initialising browser for dromaeo_css test... 07:53:23 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpPnzSP0/profile http://localhost:56528/getInfo.html) 07:53:26 INFO - PROCESS | 1917 | 2016-12-28 07:53:26.876 plugin-container[1919:19800] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.tsm.portname' 07:53:26 INFO - PROCESS | 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:53:26 INFO - PROCESS | 1917 | 2016-12-28 07:53:26.879 plugin-container[1919:19800] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.CFPasteboardClient' 07:53:26 INFO - PROCESS | 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:53:26 INFO - PROCESS | 1917 | 2016-12-28 07:53:26.879 plugin-container[1919:19800] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:53:26 INFO - PROCESS | 1917 | __metrics Screen width/height:1600/1200 07:53:26 INFO - PROCESS | 1917 | colorDepth:24 07:53:26 INFO - PROCESS | 1917 | Browser inner width/height: 1024/689 07:53:26 INFO - PROCESS | 1917 | __metrics 07:53:27 ERROR - PROCESS | 1917 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 07:53:27 INFO - TEST-INFO | 1917: exit 0 07:53:27 INFO - Browser initialized. 07:53:27 INFO - Running cycle 1/1 for dromaeo_css test... 07:53:27 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpPnzSP0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:53:30 INFO - PROCESS | 1920 | 2016-12-28 07:53:30.254 plugin-container[1921:19932] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8543, name = 'com.apple.tsm.portname' 07:53:30 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:53:30 INFO - PROCESS | 1920 | 2016-12-28 07:53:30.255 plugin-container[1921:19932] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4507, name = 'com.apple.CFPasteboardClient' 07:53:30 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:53:30 INFO - PROCESS | 1920 | 2016-12-28 07:53:30.255 plugin-container[1921:19932] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:53:39 INFO - PROCESS | 1920 | RSS: Main: 229253120 07:53:39 INFO - PROCESS | 1920 | 07:54:29 INFO - PROCESS | 1920 | 1482940469593 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] 07:56:42 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:56528/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56528/tests/dromaeo/cssquery-ext.html) 07:56:44 INFO - PROCESS | 1920 | RSS: Main: 233627648 07:56:44 INFO - PROCESS | 1920 | 07:59:47 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:56528/tests/dromaeo/cssquery-ext.html (next: http://localhost:56528/tests/dromaeo/cssquery-jquery.html) 07:59:47 INFO - PROCESS | 1920 | RSS: Main: 242929664 07:59:47 INFO - PROCESS | 1920 | 08:02:49 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:56528/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56528/tests/dromaeo/cssquery-mootools.html) 08:02:50 INFO - PROCESS | 1920 | RSS: Main: 246411264 08:02:50 INFO - PROCESS | 1920 | 08:05:52 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:56528/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56528/tests/dromaeo/cssquery-prototype.html) 08:05:52 INFO - PROCESS | 1920 | RSS: Main: 246153216 08:05:52 INFO - PROCESS | 1920 | 08:08:55 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:56528/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56528/tests/dromaeo/cssquery-yui.html) 08:08:56 INFO - PROCESS | 1920 | RSS: Main: 250400768 08:08:56 INFO - PROCESS | 1920 | 08:11:58 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:56528/tests/dromaeo/cssquery-yui.html (next: http://localhost:56528/tests/dromaeo/cssquery-dojo.html) 08:11:59 INFO - PROCESS | 1920 | RSS: Main: 251826176 08:11:59 INFO - PROCESS | 1920 | 08:11:59 INFO - PROCESS | 1920 | __start_tp_report 08:11:59 INFO - PROCESS | 1920 | _x_x_mozilla_page_load 08:11:59 INFO - PROCESS | 1920 | _x_x_mozilla_page_load_details 08:11:59 INFO - PROCESS | 1920 | |i|pagename|runs| 08:11:59 INFO - PROCESS | 1920 | |0;dojo.html;2448.68167138272;2854.7430731234185;3039.90563397177;3379.766385269377;3434.2272988577574;5277.261183434322;5798.797042103527;5807.506361959233;5844.678542680156;5854.946109700252;4845.861222612683;5865.665081711752;5937.22816033915;5938.940610593892;5987.940120598792;4998.325226094476;5323.387810401795;5363.973180134094;5382.138857782756;5417.485338892819;5400.945990540084;5416.21464887593;5458.918116228259;5462.808801691942;5489.835304940838;5249.580033597303;5281;5406.729663516828;5414.40441551429;5423.83728488146;5208.348956380452;5309.761060752276;5340.546053585431;5343.038253114438;5383.703896285702;4349.238883195429;5003.924941125886;5281.260623512711;5317.734113294358;5456.399796022434;5942.851428714273;5992.730327135289;6009.699515024275;6014.669193194372;6049.788257411014;6128.448439640454;6289.842753931142;6306.211723534559;6356.872862542769;6362.809115726535;6167.044108163242;6223.906641400383;6247.718852651642;6259.311475737665;6281.434670879643;6007.189029481052;6008.36912124225;6019.638821670714;6020.217371741689;6050.092486127072;6429.32492088324;6450.387213214737;6470.741170353133;6479.6760161991715;6503.512236582275;6129.785457508966;6146.600470969463;6159.784407545804;6231.937680623227;6298.874022519524;6237.563370564108;6243.250809902841;6259.499240060784;6270.7491700331475;6353.5234857385885;6254.937450625526;6270.278917924497;6315.747370105236;6318.210223722035;6320.968395157995;6240.375962403723;6282.905756413657;6309.211348581427;6319.842003949846;6330.778422755181;4895.7062576244825;4940.283658869444;4956.8760780980765;4963.652544321935;5097.490250974873;4477.19410506112;4480.731156130578;4511.390962220058;4534.546545345439;4538.387317712132;4663.510331415153;5937.139114828326;5981.910271345933;5993.520518358521;6007.038873780174;6211.689415529206;6238.376162383816;6274.403931626488;6297.338779428094;6354.650494222862;5882.970585147047;5968.045112781934;5982.192404025488;5999;6021.096835474714;5355.3305836770405;5411.729413529308;5412.07994640912;5486.423925487767;5523.28197334344;6370.74517019314;6458.806235812933;6459.709313080829;6474.384933431316;6486.805395838132;6213.1922850029205;6324.177856878577;6330.651814150266;6331.556791024584;6346.2067241594805;6340.207474065742;6369.8726025480155;6413.775517856852;6416.262129855127;6522.543421960607;6291.748330066746;6297.685115744102;6309.148264984168;6309.927312356818;6316.3367846377305;6322.841928951629;6328.588641738273;6350;6351.555391122762;6353.650549219838;4680.368150299802;5327.786888524415;6110.602810817282;6118.326984031842;6130.428711073822;6093.116498107661;6164.8767024658355;6176.629402235881;6198.132261483306;6291.182146321132;5508.732991412078;5765.596408251382;6127.816165515042;6165.537584681257;6187.876242475215;4947.554720075211;5408;6155.322914479314;6202.069689546604;6216.564840461305;4385.276429389115;4914.115459217375;5067.721275329893;5516.586256030733;5661.858453538694 08:11:59 INFO - PROCESS | 1920 | |1;ext.html;443.8845900065986;479.9280107983803;483.59619717535855;497.3484734997153;522.6132661830244;32152;34292.628294868235;38527;38856.222875542546;40200;33390.33219335612;40128.59871401285;40316.596834031654;40576.59423405765;42913.92715182122;7651.846963060735;9226.861597076017;9343.018983006788;9373.906260937405;9383.81232375352;9274;9288.535573221347;9418.5761640726;9492.050794920488;9498.572564234591;9490.383125096881;9538;9539.80920381592;9550.856737148948;9569.904300957005;9456.432614043146;9461.810763784686;9512.191463725592;9515.381500202464;9520.571574279105;9253.259739220914;9287.58205880737;9329.16037556623;9354.953225233898;9355.719328420158;38985.805070974464;39209.41185882214;39218;39371.40942885859;39688;3270.2968861694612;3299.191698033973;3349.69852713257;3480.5301284326547;3759.812009399519;28096.297592560146;28689.28276793097;29295.853520732475;30787.076387708406;31114.222144446343;2622.8295160814487;3112.097491727396;3150.5116706910235;3231.83840807961;3252.674732526752;346063.9999999975;348474;349058;349792.25103874574;352585;58290;58606;58630.413695862495;58858.70570647119;58929;59969.10046349308;60221;60468;60508;60959.78080438455;15605.843941560666;15680.76478852818;15842.128682922319;15865.682686346208;15872.968257063254;12246.938765306117;12258.957988571072;12288.508459661693;12307.953823924887;12346.197497162835;15382.538523844303;15779.211039448212;15784.368625254861;15834.60413489672;15864;3196.2488815128427;6586.50601204902;6774.45804335652;6777.2545020047755;6797.2523022467485;849.2950850793826;945.628838184635;963.393062370702;965.9855102173473;1034.8447732840134;29058.7094129056;29558.852205738833;29788;29874;30291.091267262018;1040.8907064758243;1068.8824229334766;1112.4993752811267;1115.939857135708;1121.360824330124;12689.428975696115;13680.384382702803;13908.860911390757;14926.626834329229;15300.770488442682;622674;641566;680122;681636;681689;56110;56516.739330426324;56709.7164514183;56766;56910;1343.657367371314;1363.7749771287824;1380.6341319550327;1383.100984360174;1391.1775573997893;28400.43199136047;29179.124626261248;29200.853995729885;29209.269768255963;29486.852565737892;40918.18163636651;46833.531664682916;48876.02247955092;48892.75553622209;49520.75239623779;606352;609455;610656;611392;611670;22754.976026078533;24900.501989960463;25027;25189.24432267035;25220.24339269825;43527.564724353615;43991.78004109959;44349;45634.63096107122;45938.77030614825;28590.714092858805;32096.67903320937;35836.566537338214;37163;37361.4395784058;37273.440898386005;38063.61936380711;38116.42825357677;38307.977662346166;38789;36905.26189476142;37031.814840925625;37039.81480092582;37043.44434833534;38022.61977380191;31772.205694857814;33768.3246335067;34244.657553425146;34405;34431.13922151966;25278.736063197135;25327.62008569836;25389.619155713048;25711.74288257168;26060.34849128787 08:11:59 INFO - PROCESS | 1920 | |2;jquery.html;366.3351017902507;366.4924080148993;378.4096808977996;378.94505296731984;396.0929471510242;18527.907360463196;18568.35010774623;18828.81171188291;19126.904365478153;19311.903440482813;11427.828582571246;11687;11827.763444731101;11831.881681183186;11834.526618935231;4909.459959404463;4964.478729733371;5199.610029247821;5350.117230656937;5423.322084739408;3835.61643835617;4026.6979976501734;5294.788208471657;5671.177679236508;5955.076963070731;6006.489448396892;6016;6036.15493830864;6051.878962420749;6053.697315134248;5774.133879918004;5985.970070149643;6039.849003774897;6043.8186854394435;6075.210222671069;5597.468240517144;5777.711114444261;5844.853878653025;5855.677937713424;5873.089671100986;12963.15739476931;13004.804927926089;13011.154274972096;13022.609321720363;15461.92269038659;2173.739151301854;2284.920027799036;2358.445765245166;2567.717551069381;2785.832850029005;9559.22455253114;9605.567749451291;10338.534765935476;10721;15761.448349307719;1582.485692150056;1697.73685078813;1866.2348437140843;1878.4552479780848;1967.3802752133033;90959;104762.95237047608;118775.40612296884;124640.37679811542;125610;11070.225084244186;11108.389038602792;11386.316820990767;11445.599404020819;11629.1859569831;14574.19841908684;14602.561923142322;14619.853801462063;14635.92682036583;14640.267986600627;8304.418690691593;8503.532305723245;8534.658613655509;8561.700340488052;8643.740687779376;7011.614361210182;8328.375371847136;8376.246137847624;8477.406581539357;8536.8719469208;3741.607131251234;4518.548145185455;4607.285870690049;4624.491305956342;4678.578927896506;1424.3519198764536;1584.3662534986097;1661.2856471717037;1916.3963351544219;1964.6168997045438;825.1748251748252;876.5748611923212;884.3102380143498;908.4268015723584;955.1308309438377;19588.412347629594;20331.597725387503;20423.795762042188;20485.2830150944;20793.272235471988;895.901450840407;910.2490445382585;915.7481692534607;943.9386439881523;997.2221667099674;6680.432163266079;10772.622958196425;11299.717507062225;11782.705432364088;11814.88185118155;603987;614354.7129057483;615779;622363;626182;125100.1234981476;125336;127773.36113319374;131636;132853;960.2653969713175;979.4172467381896;999.4558407260799;1003.8694969653898;1004.3321191407632;16435.26041328119;17847.37534186297;18936.810631893506;18972.146253418352;19138.947357894893;47990;48347;49861.002779943476;50139;50165.74585635388;74215.62892185505;76261;77892;78392.60803695946;79105;8005.599720014093;8037.115917249215;8265.214804593554;8297.502149871027;8407.95796021016;11211.831822522505;12328.445219965302;12460.750784984431;12617.747645047231;12877.291748953898;8260.173982601691;8798.956005219932;9137.583674530459;10438.164946804085;10816.891831081588;8814.955925220333;9841.81898172224;10450.895491045296;10583.576656933636;10791.946040269748;5984.521238300838;10559.472026398496;10855.023047925724;10901.618443354444;11039.834402483808;6954;8078.6364613593705;8108.472949258279;8186.672533098609;8294.046184688837;9447.096658210841;10193.133583645176;10501.632442864464;10820.296680715728;10893 08:11:59 INFO - PROCESS | 1920 | |3;mootools.html;1435.7508967198535;1463.5975106845617;1569.952901412958;1609.9678006439872;1732.5408766676833;7509.812254693636;7741.7677469675855;7998.1601931797195;8444.324454043677;8452.8732069019;7908.0905695844995;8230.176982301768;8230.794230144249;8263.793405164874;8274.62764175612;2277.954440911181;2297.850639708422;2328.639060945556;2331.6619090231907;2337;2276.2829708641807;2343.484433424644;2361.7992470640015;2364.054378248697;2366.7869891709743;1919.8560107991887;2310.260716570698;2322.2336628912417;2331.4521087544413;2337.859728416292;1933.2460340467228;2288.336382449088;2297.253392647396;2309.0532881518666;2313.8611683299055;2337.5558643857785;2344.0858065354523;2348.9177878774162;2349.8707571083582;2373.8931748071377;8246.505209687439;8248.46384984974;8335.624896879655;8337.95831020847;8403.495790252604;5063.367079115111;5208.713520756357;5212.791488340462;5229.973850130763;5241.73791310433;2632.93417664558;3140.795848269878;3434.072800343918;3434.8282585870606;3443.2941247044296;1008.1078245752489;1042.989570104297;1044.2742294105599;1056.3186744549798;1056.7410984308895;10456.215783816244;10590.311629744043;10725.14198864089;10756.73108172294;10919.617813376493;3962.484876965976;4309.073549186877;4429.269170586882;4674.439067311876;4709.576138147515;8322.16778322163;8355.832883342298;8360.540170290571;8366.665333386718;8376.748697539084;4884.755762211875;4885.316055752198;4894.070126675921;4906.533879281463;4909.337239472697;4253.468316460442;4378.365137055109;4501.279795232813;4575.10785396851;4707.552782485727;3827.7129215308964;3843.36584463566;3906.9609303907164;3930.4890364252465;3942.9211415771524;1250.3123282194938;1269.51123817331;1277.2974863824859;1289.8258735070644;1290.2452065541763;790.0440467034952;845.9323254139654;863.3222920007764;867.6052396159859;892.1791951404723;5400.432954539795;5481.479259470344;5483.052202414256;5493.450654934474;5566.66600003993;432.2932006169935;432.36010704158025;435.9716618419856;762.9732959346396;906.5331354352513;3132.5144602586633;3322.501624756306;3435.587729472479;3661.8901432957055;3732.104294969188;9708.271879609058;10164;10176.185905127571;10181;10225.539850706737;8983.775405614912;9244.399114057565;9689.51552422376;9875.851862222073;9942.552585133542;6268.780592679234;7366.594837284001;7873.1733168016535;7874.409419293576;7875.527468351917;4423.358613001161;5259.526642602184;5260.736963151904;5531.363893152178;5549.667019978814;8983.371164018456;9241.907580924104;9344.252459803336;9388.342816002814;9429.52852357393;9157.130072643089;9287.860682089638;9471.337006409485;9603.711888643353;9608.039196080335;7512.060992375953;7707.961460192663;7726.652300646597;7833.647485863035;7872.84254314922;7946.284834364935;7964.482308649919;7965.163657816078;8020.719274825353;8059.3552515799765;7100.218975912749;7111.359977602041;7127.501074924913;7176.96411517939;7193.964030179815;6865.41643960249;6959.512834101564;6967.1291088613925;7090.787276381717;7126.5011449198055;5579.302587176603;6361.332060133619;6541.476681865534;6558.967205163944;6576;4622.353333964487;4777.546133117348;4813.663043586916;4848.903021939471;4896.996115796324;4721.220998535203;4722.126406614787;4822.68652537584;4851.078295123915;4933.802647894044 08:11:59 INFO - PROCESS | 1920 | |4;prototype.html;296.1530024130985;302.1931443047065;309.12363449620324;309.3349299007135;312.38927895963394;5219.686818790875;5323.657590660112;5403.972980135104;5472.26124473196;5481.588880833939;4238.766867822269;5311.954897364513;5356.3572371315395;5372;5385.192221166827;3610.4042832932537;4119.649829764466;4136.048708796979;4142.032534546097;4149.875503734893;2905.331773692052;4032.5362583303004;4093.897652558695;4135.60711732385;4169.437125987984;3619.963800362002;3862.9806850965706;3880.417937309412;4080.1227736036744;4116.855910043149;3546.840392182345;3975.70182236332;4113.938290925638;4116.526599441073;4120.114175452292;2993.925151871199;3574.6782789549065;3596.0512379848533;3603.657652523006;3612.187257866985;4021.9798901005306;4935.506449355072;5180.948190518085;5258.421573626898;5258.684478931238;3890.1247219375414;4203.600657937491;4659.324397962277;4716.490722968651;4723.173444647173;4607.65944690097;5596.916046259309;5633.521150702195;5639.210510528529;5643.294588176478;3649.1059690375764;3777.8299976501125;3942.2903877302074;3954.782486963216;3983.521977362706;5972.923944168757;5991.191189189491;5992.550558708071;6002.309734380515;6031.577789554733;3999.560048394674;4021.959780402217;4028.033272014754;4041.6160464755803;4053.8175782089875;3510.929781404409;3657.2868290683064;3845.9807700961874;3882.9029274267805;4104.343305071175;3862.980685096613;3945.7237993340764;4576.382188404523;4586.289125185602;4602.562756538124;4259.424977628042;4299.634531064895;5258.1849813279005;5260.894782104337;5687.146927960756;1062.3148069495132;1143.982840257397;1184.8755880632582;1262.2741923394085;1281.0520215040804;825.0594322472386;845.9890430935096;867.9324430949966;872.3152325424511;874.7725591346295;4305.677074219446;4360.803763830635;4458.621017213508;4499.955000450019;4894.252575002242;5464.863378415492;5490.752916118784;5516.668999859941;5518.972405137949;5542.889142217214;4349.3041113421705;4816.373871396766;4836.250381190921;5019.139090718329;5029.84910452687;2877.331402461836;2887.7978541501893;2888.234617826278;2889.5954566360724;2927.238917881366;4967.453580106186;5226.425093239741;5379.224855797577;5689.573282003867;5764.538836893039;5035.169197082514;5344.866378340573;5568.635684257302;5622.409646987089;5658.462446067617;5703.657780533182;5708.40061793514;5717.998051023246;5718.768895573988;5720.227769251181;5171.198464238049;5441.646292990943;5485.177223416519;5488.396276409671;5566.666000040011;4459.9038451927545;4677.976610116928;5021;5422.403535611001;5453.427390123979;5305.965336759295;5735.426457354231;5745.798897038583;5748.425157484218;5774.711264436845;3126.609173853268;3675.9965818628375;3676.7977761223387;3737.6449237322413;3835.0028992461603;5271.1302635064785;5316.760745766527;5338.786448542015;5462.962037212917;5487.149491828773;5062.974685126551;5305.07161246788;5377.516023557899;5396.433374495621;5571.749271282721;5191.07930065878;5655;5663.762813688488;5682.545396368363;5692.886142277049;4749.833755818529;4904.681195722266;5351.384590772111;5521.549031855091;5534.861628459321;5636.1827535008015;5649.378568357395;5652.3217213934595;5684.516816070515;5753.165790960371;4708.458527269408;5169.327987361619;5185.533302002837;5577.637453565505;5586.692731899784 08:11:59 INFO - PROCESS | 1920 | |5;yui.html;598.6318414175283;699.3635791429798;721.588694444167;722.2993696114769;729.4383324839873;13981.30093495325;14838.703225935475;14872;14876.702465950675;14939.626509337271;11017.559297628106;13288.13627114237;13326;13362.933185334085;13394.59816205513;642.8135840606219;664.0901964308904;710.2932582080837;789.8736202207648;829.2371018662844;802.3701394405393;820.0774129104757;829.6059371798408;838.7525679924437;839.870374346503;877.534906499553;882.3823323673422;883.1609970527992;884.4251236696167;885.0706757904208;857.5583574459127;871.4161511787117;871.9128087191261;872.4198408058634;872.8341615093112;639.6769631336145;655.1810237203496;655.1810237203496;656.1044174701545;657.135866335767;13262.270575118364;13417.66455838612;13600.795988060187;13611.11527750693;14170.504032358818;13194.274314912676;13360.265185414799;13405.932970335183;13616.86383136166;13723.284589426326;10727.409992450412;10905.890941090567;10967.780644387067;11048.116150707923;11214.719632009184;3527.576690797095;3612.4581312803025;3641.890743277706;3670.082479380155;3673.3204357194004;85002;85373;86228.27543449159;86757.56621216917;87410.56294718549;9596.656468094472;10281.485925703684;10346.22403319754;10397.012283833024;10405.739856503647;18324.81675183258;20154.09306581178;20272;20367.4908127298;20732;6337.76413599353;6718.395344418928;7305.3425191732995;7434.516756410816;7566.35685966688;4100.856470023595;4151.7924103794685;4227.175700738388;4240.745555266694;4294.6993710439965;3225.2420681139915;3289.5723555937575;3305.3554556861664;3331.2338162222823;3378.983105084508;597.4644914863791;603.667982609563;608.1546650156287;616.6854903998993;618.7617767159586;586.2115454713404;591.4942723971029;609.6464050850497;640.3788325324427;642.6722371590522;6847.637565040053;8401.36989725773;8671.6531338747;8991.865122023175;9069.460551045719;509.77824646278987;550.6310771782915;554.3541773833439;640.1262276991965;661.024988142486;4266.722663026893;4435.223835828717;4444.799984000728;4495.280755079237;4517.119161763425;79170;89051;115245;119473;120263.797362027;95632.52183739176;95700;106340.46829765956;107224.46387768167;108619;55611.721941390846;64534.354656453776;88663.55668221618;93277.5336123315;95507.56738648926;9153.816923661623;9596.424214547149;9746.512674366395;9801.11789938909;9801.166900813225;41483.792581036905;43130.78434607807;46605.53394466012;51459.48540514547;53921;45818.770906145255;52284;65167.674161628885;66755.66622166858;70918.64540677263;11853.644390668294;11905.345206013752;11973.76052478963;12167.878321216675;12205.145639805129;13575.185488870698;13902.51341203053;13964.650883727989;14147.575572732834;14309.499167529086;12110.576129835767;13166.40751166219;13228.140170889246;13433.798493022417;13632.113912595649;12589.87410125887;13032.218066916015;13199.868001319865;13201.735965280832;13336.599902002956;12505.93747031259;12637.115401921777;12749.043821713223;13080.28058456794;13153.60539183826;9457.19613832832;10304.536295866554;10973.945130274617;10992.230543861853;11035.83446248323;10852.294600850919;10943.45282735876;11153.609623663133;11188.272762270426;11258.718532036437 08:11:59 INFO - PROCESS | 1920 | __end_tp_report 08:11:59 INFO - PROCESS | 1920 | __start_cc_report 08:11:59 INFO - PROCESS | 1920 | _x_x_mozilla_cycle_collect,381 08:11:59 INFO - PROCESS | 1920 | __end_cc_report 08:11:59 INFO - PROCESS | 1920 | __startTimestamp1482941519138__endTimestamp 08:11:59 INFO - PROCESS | 1920 | 08:11:59 INFO - PROCESS | 1920 | ------- Summary: start ------- 08:11:59 INFO - PROCESS | 1920 | Number of tests: 6 08:11:59 INFO - PROCESS | 1920 | 08:11:59 INFO - PROCESS | 1920 | [#0] dojo.html Cycles:165 Average:5770.77 Median:6020.22 stddev:732.49 (12.2%) stddev-sans-first:686.81 08:11:59 INFO - PROCESS | 1920 | Values: 2448.7 2854.7 3039.9 3379.8 3434.2 5277.3 5798.8 5807.5 5844.7 5854.9 4845.9 5865.7 5937.2 5938.9 5987.9 4998.3 5323.4 5364.0 5382.1 5417.5 5400.9 5416.2 5458.9 5462.8 5489.8 5249.6 5281.0 5406.7 5414.4 5423.8 5208.3 5309.8 5340.5 5343.0 5383.7 4349.2 5003.9 5281.3 5317.7 5456.4 5942.9 5992.7 6009.7 6014.7 6049.8 6128.4 6289.8 6306.2 6356.9 6362.8 6167.0 6223.9 6247.7 6259.3 6281.4 6007.2 6008.4 6019.6 6020.2 6050.1 6429.3 6450.4 6470.7 6479.7 6503.5 6129.8 6146.6 6159.8 6231.9 6298.9 6237.6 6243.3 6259.5 6270.7 6353.5 6254.9 6270.3 6315.7 6318.2 6321.0 6240.4 6282.9 6309.2 6319.8 6330.8 4895.7 4940.3 4956.9 4963.7 5097.5 4477.2 4480.7 4511.4 4534.5 4538.4 4663.5 5937.1 5981.9 5993.5 6007.0 6211.7 6238.4 6274.4 6297.3 6354.7 5883.0 5968.0 5982.2 5999.0 6021.1 5355.3 5411.7 5412.1 5486.4 5523.3 6370.7 6458.8 6459.7 6474.4 6486.8 6213.2 6324.2 6330.7 6331.6 6346.2 6340.2 6369.9 6413.8 6416.3 6522.5 6291.7 6297.7 6309.1 6309.9 6316.3 6322.8 6328.6 6350.0 6351.6 6353.7 4680.4 5327.8 6110.6 6118.3 6130.4 6093.1 6164.9 6176.6 6198.1 6291.2 5508.7 5765.6 6127.8 6165.5 6187.9 4947.6 5408.0 6155.3 6202.1 6216.6 4385.3 4914.1 5067.7 5516.6 5661.9 08:11:59 INFO - PROCESS | 1920 | 08:11:59 INFO - PROCESS | 1920 | [#1] ext.html Cycles:180 Average:66846.80 Median:38043.12 stddev:149549.71 (393.1%) stddev-sans-first:149886.13 08:11:59 INFO - PROCESS | 1920 | Values: 443.9 479.9 483.6 497.3 522.6 32152.0 34292.6 38527.0 38856.2 40200.0 33390.3 40128.6 40316.6 40576.6 42913.9 7651.8 9226.9 9343.0 9373.9 9383.8 9274.0 9288.5 9418.6 9492.1 9498.6 9490.4 9538.0 9539.8 9550.9 9569.9 9456.4 9461.8 9512.2 9515.4 9520.6 9253.3 9287.6 9329.2 9355.0 9355.7 38985.8 39209.4 39218.0 39371.4 39688.0 3270.3 3299.2 3349.7 3480.5 3759.8 28096.3 28689.3 29295.9 30787.1 31114.2 2622.8 3112.1 3150.5 3231.8 3252.7 346064.0 348474.0 349058.0 349792.3 352585.0 58290.0 58606.0 58630.4 58858.7 58929.0 59969.1 60221.0 60468.0 60508.0 60959.8 15605.8 15680.8 15842.1 15865.7 15873.0 12246.9 12259.0 12288.5 12308.0 12346.2 15382.5 15779.2 15784.4 15834.6 15864.0 3196.2 6586.5 6774.5 6777.3 6797.3 849.3 945.6 963.4 966.0 1034.8 29058.7 29558.9 29788.0 29874.0 30291.1 1040.9 1068.9 1112.5 1115.9 1121.4 12689.4 13680.4 13908.9 14926.6 15300.8 622674.0 641566.0 680122.0 681636.0 681689.0 56110.0 56516.7 56709.7 56766.0 56910.0 1343.7 1363.8 1380.6 1383.1 1391.2 28400.4 29179.1 29200.9 29209.3 29486.9 40918.2 46833.5 48876.0 48892.8 49520.8 606352.0 609455.0 610656.0 611392.0 611670.0 22755.0 24900.5 25027.0 25189.2 25220.2 43527.6 43991.8 44349.0 45634.6 45938.8 28590.7 32096.7 35836.6 37163.0 37361.4 37273.4 38063.6 38116.4 38308.0 38789.0 36905.3 37031.8 37039.8 37043.4 38022.6 31772.2 33768.3 34244.7 34405.0 34431.1 25278.7 25327.6 25389.6 25711.7 26060.3 08:11:59 INFO - PROCESS | 1920 | 08:11:59 INFO - PROCESS | 1920 | [#2] jquery.html Cycles:180 Average:34477.51 Median:2107.28 stddev:102790.88 (4877.9%) stddev-sans-first:103047.32 08:11:59 INFO - PROCESS | 1920 | Values: 366.3 366.5 378.4 378.9 396.1 18527.9 18568.4 18828.8 19126.9 19311.9 11427.8 11687.0 11827.8 11831.9 11834.5 4909.5 4964.5 5199.6 5350.1 5423.3 3835.6 4026.7 5294.8 5671.2 5955.1 6006.5 6016.0 6036.2 6051.9 6053.7 5774.1 5986.0 6039.8 6043.8 6075.2 5597.5 5777.7 5844.9 5855.7 5873.1 12963.2 13004.8 13011.2 13022.6 15461.9 2173.7 2284.9 2358.4 2567.7 2785.8 9559.2 9605.6 10338.5 10721.0 15761.4 1582.5 1697.7 1866.2 1878.5 1967.4 90959.0 104763.0 118775.4 124640.4 125610.0 11070.2 11108.4 11386.3 11445.6 11629.2 14574.2 14602.6 14619.9 14635.9 14640.3 8304.4 8503.5 8534.7 8561.7 8643.7 7011.6 8328.4 8376.2 8477.4 8536.9 3741.6 4518.5 4607.3 4624.5 4678.6 1424.4 1584.4 1661.3 1916.4 1964.6 825.2 876.6 884.3 908.4 955.1 19588.4 20331.6 20423.8 20485.3 20793.3 895.9 910.2 915.7 943.9 997.2 6680.4 10772.6 11299.7 11782.7 11814.9 603987.0 614354.7 615779.0 622363.0 626182.0 125100.1 125336.0 127773.4 131636.0 132853.0 960.3 979.4 999.5 1003.9 1004.3 16435.3 17847.4 18936.8 18972.1 19138.9 47990.0 48347.0 49861.0 50139.0 50165.7 74215.6 76261.0 77892.0 78392.6 79105.0 8005.6 8037.1 8265.2 8297.5 8408.0 11211.8 12328.4 12460.8 12617.7 12877.3 8260.2 8799.0 9137.6 10438.2 10816.9 8815.0 9841.8 10450.9 10583.6 10791.9 5984.5 10559.5 10855.0 10901.6 11039.8 6954.0 8078.6 8108.5 8186.7 8294.0 9447.1 10193.1 10501.6 10820.3 10893.0 08:11:59 INFO - PROCESS | 1920 | 08:11:59 INFO - PROCESS | 1920 | [#3] mootools.html Cycles:180 Average:5338.98 Median:4907.94 stddev:2946.91 (60.0%) stddev-sans-first:2940.58 08:11:59 INFO - PROCESS | 1920 | Values: 1435.8 1463.6 1570.0 1610.0 1732.5 7509.8 7741.8 7998.2 8444.3 8452.9 7908.1 8230.2 8230.8 8263.8 8274.6 2278.0 2297.9 2328.6 2331.7 2337.0 2276.3 2343.5 2361.8 2364.1 2366.8 1919.9 2310.3 2322.2 2331.5 2337.9 1933.2 2288.3 2297.3 2309.1 2313.9 2337.6 2344.1 2348.9 2349.9 2373.9 8246.5 8248.5 8335.6 8338.0 8403.5 5063.4 5208.7 5212.8 5230.0 5241.7 2632.9 3140.8 3434.1 3434.8 3443.3 1008.1 1043.0 1044.3 1056.3 1056.7 10456.2 10590.3 10725.1 10756.7 10919.6 3962.5 4309.1 4429.3 4674.4 4709.6 8322.2 8355.8 8360.5 8366.7 8376.7 4884.8 4885.3 4894.1 4906.5 4909.3 4253.5 4378.4 4501.3 4575.1 4707.6 3827.7 3843.4 3907.0 3930.5 3942.9 1250.3 1269.5 1277.3 1289.8 1290.2 790.0 845.9 863.3 867.6 892.2 5400.4 5481.5 5483.1 5493.5 5566.7 432.3 432.4 436.0 763.0 906.5 3132.5 3322.5 3435.6 3661.9 3732.1 9708.3 10164.0 10176.2 10181.0 10225.5 8983.8 9244.4 9689.5 9875.9 9942.6 6268.8 7366.6 7873.2 7874.4 7875.5 4423.4 5259.5 5260.7 5531.4 5549.7 8983.4 9241.9 9344.3 9388.3 9429.5 9157.1 9287.9 9471.3 9603.7 9608.0 7512.1 7708.0 7726.7 7833.6 7872.8 7946.3 7964.5 7965.2 8020.7 8059.4 7100.2 7111.4 7127.5 7177.0 7194.0 6865.4 6959.5 6967.1 7090.8 7126.5 5579.3 6361.3 6541.5 6559.0 6576.0 4622.4 4777.5 4813.7 4848.9 4897.0 4721.2 4722.1 4822.7 4851.1 4933.8 08:11:59 INFO - PROCESS | 1920 | 08:11:59 INFO - PROCESS | 1920 | [#4] prototype.html Cycles:180 Average:4452.60 Median:5020.07 stddev:1373.34 (27.4%) stddev-sans-first:1341.29 08:11:59 INFO - PROCESS | 1920 | Values: 296.2 302.2 309.1 309.3 312.4 5219.7 5323.7 5404.0 5472.3 5481.6 4238.8 5312.0 5356.4 5372.0 5385.2 3610.4 4119.6 4136.0 4142.0 4149.9 2905.3 4032.5 4093.9 4135.6 4169.4 3620.0 3863.0 3880.4 4080.1 4116.9 3546.8 3975.7 4113.9 4116.5 4120.1 2993.9 3574.7 3596.1 3603.7 3612.2 4022.0 4935.5 5180.9 5258.4 5258.7 3890.1 4203.6 4659.3 4716.5 4723.2 4607.7 5596.9 5633.5 5639.2 5643.3 3649.1 3777.8 3942.3 3954.8 3983.5 5972.9 5991.2 5992.6 6002.3 6031.6 3999.6 4022.0 4028.0 4041.6 4053.8 3510.9 3657.3 3846.0 3882.9 4104.3 3863.0 3945.7 4576.4 4586.3 4602.6 4259.4 4299.6 5258.2 5260.9 5687.1 1062.3 1144.0 1184.9 1262.3 1281.1 825.1 846.0 867.9 872.3 874.8 4305.7 4360.8 4458.6 4500.0 4894.3 5464.9 5490.8 5516.7 5519.0 5542.9 4349.3 4816.4 4836.3 5019.1 5029.8 2877.3 2887.8 2888.2 2889.6 2927.2 4967.5 5226.4 5379.2 5689.6 5764.5 5035.2 5344.9 5568.6 5622.4 5658.5 5703.7 5708.4 5718.0 5718.8 5720.2 5171.2 5441.6 5485.2 5488.4 5566.7 4459.9 4678.0 5021.0 5422.4 5453.4 5306.0 5735.4 5745.8 5748.4 5774.7 3126.6 3676.0 3676.8 3737.6 3835.0 5271.1 5316.8 5338.8 5463.0 5487.1 5063.0 5305.1 5377.5 5396.4 5571.7 5191.1 5655.0 5663.8 5682.5 5692.9 4749.8 4904.7 5351.4 5521.5 5534.9 5636.2 5649.4 5652.3 5684.5 5753.2 4708.5 5169.3 5185.5 5577.6 5586.7 08:11:59 INFO - PROCESS | 1920 | 08:11:59 INFO - PROCESS | 1920 | [#5] yui.html Cycles:180 Average:19724.56 Median:23712.74 stddev:29208.05 (123.2%) stddev-sans-first:29254.68 08:11:59 INFO - PROCESS | 1920 | Values: 598.6 699.4 721.6 722.3 729.4 13981.3 14838.7 14872.0 14876.7 14939.6 11017.6 13288.1 13326.0 13362.9 13394.6 642.8 664.1 710.3 789.9 829.2 802.4 820.1 829.6 838.8 839.9 877.5 882.4 883.2 884.4 885.1 857.6 871.4 871.9 872.4 872.8 639.7 655.2 655.2 656.1 657.1 13262.3 13417.7 13600.8 13611.1 14170.5 13194.3 13360.3 13405.9 13616.9 13723.3 10727.4 10905.9 10967.8 11048.1 11214.7 3527.6 3612.5 3641.9 3670.1 3673.3 85002.0 85373.0 86228.3 86757.6 87410.6 9596.7 10281.5 10346.2 10397.0 10405.7 18324.8 20154.1 20272.0 20367.5 20732.0 6337.8 6718.4 7305.3 7434.5 7566.4 4100.9 4151.8 4227.2 4240.7 4294.7 3225.2 3289.6 3305.4 3331.2 3379.0 597.5 603.7 608.2 616.7 618.8 586.2 591.5 609.6 640.4 642.7 6847.6 8401.4 8671.7 8991.9 9069.5 509.8 550.6 554.4 640.1 661.0 4266.7 4435.2 4444.8 4495.3 4517.1 79170.0 89051.0 115245.0 119473.0 120263.8 95632.5 95700.0 106340.5 107224.5 108619.0 55611.7 64534.4 88663.6 93277.5 95507.6 9153.8 9596.4 9746.5 9801.1 9801.2 41483.8 43130.8 46605.5 51459.5 53921.0 45818.8 52284.0 65167.7 66755.7 70918.6 11853.6 11905.3 11973.8 12167.9 12205.1 13575.2 13902.5 13964.7 14147.6 14309.5 12110.6 13166.4 13228.1 13433.8 13632.1 12589.9 13032.2 13199.9 13201.7 13336.6 12505.9 12637.1 12749.0 13080.3 13153.6 9457.2 10304.5 10973.9 10992.2 11035.8 10852.3 10943.5 11153.6 11188.3 11258.7 08:11:59 INFO - PROCESS | 1920 | -------- Summary: end -------- 08:11:59 INFO - PROCESS | 1920 | 08:11:59 INFO - TEST-INFO | 1920: exit 0 08:11:59 INFO - TEST-OK | dromaeo_css | took 1115919ms 08:11:59 INFO - TEST-START | kraken 08:11:59 INFO - operating with platform_type : mac_ 08:11:59 INFO - Initialising browser for kraken test... 08:11:59 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpkBkvXo/profile http://localhost:56528/getInfo.html) 08:12:02 INFO - PROCESS | 1926 | 2016-12-28 08:12:02.789 plugin-container[1927:22197] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8637, name = 'com.apple.tsm.portname' 08:12:02 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:12:02 INFO - PROCESS | 1926 | 2016-12-28 08:12:02.792 plugin-container[1927:22197] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 08:12:02 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:12:02 INFO - PROCESS | 1926 | 2016-12-28 08:12:02.792 plugin-container[1927:22197] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:12:02 INFO - PROCESS | 1926 | __metrics Screen width/height:1600/1200 08:12:02 INFO - PROCESS | 1926 | colorDepth:24 08:12:02 INFO - PROCESS | 1926 | Browser inner width/height: 1024/689 08:12:02 INFO - PROCESS | 1926 | __metrics 08:12:02 ERROR - PROCESS | 1926 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:12:03 INFO - TEST-INFO | 1926: exit 0 08:12:03 INFO - Browser initialized. 08:12:03 INFO - Running cycle 1/1 for kraken test... 08:12:03 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpkBkvXo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:12:06 INFO - PROCESS | 1928 | 2016-12-28 08:12:06.215 plugin-container[1929:22325] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 08:12:06 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:12:06 INFO - PROCESS | 1928 | 2016-12-28 08:12:06.215 plugin-container[1929:22325] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 08:12:06 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:12:06 INFO - PROCESS | 1928 | 2016-12-28 08:12:06.215 plugin-container[1929:22325] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:12:15 INFO - PROCESS | 1928 | RSS: Main: 229711872 08:12:15 INFO - PROCESS | 1928 | 08:12:18 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56528/tests/kraken/driver.html?testName=audio-beat-detection) 08:12:19 INFO - PROCESS | 1928 | RSS: Main: 241631232 08:12:19 INFO - PROCESS | 1928 | 08:12:22 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56528/tests/kraken/driver.html?testName=audio-dft) 08:12:23 INFO - PROCESS | 1928 | RSS: Main: 249270272 08:12:23 INFO - PROCESS | 1928 | 08:12:26 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56528/tests/kraken/driver.html?testName=audio-fft) 08:12:26 INFO - PROCESS | 1928 | RSS: Main: 253038592 08:12:26 INFO - PROCESS | 1928 | 08:12:28 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56528/tests/kraken/driver.html?testName=audio-oscillator) 08:12:29 INFO - PROCESS | 1928 | RSS: Main: 254332928 08:12:29 INFO - PROCESS | 1928 | 08:12:31 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56528/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:12:32 INFO - PROCESS | 1928 | RSS: Main: 255111168 08:12:32 INFO - PROCESS | 1928 | 08:12:35 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56528/tests/kraken/driver.html?testName=imaging-darkroom) 08:12:35 INFO - PROCESS | 1928 | RSS: Main: 259215360 08:12:35 INFO - PROCESS | 1928 | 08:12:38 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56528/tests/kraken/driver.html?testName=imaging-desaturate) 08:12:39 INFO - PROCESS | 1928 | RSS: Main: 258351104 08:12:39 INFO - PROCESS | 1928 | 08:12:42 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56528/tests/kraken/driver.html?testName=json-parse-financial) 08:12:43 INFO - PROCESS | 1928 | RSS: Main: 258428928 08:12:43 INFO - PROCESS | 1928 | 08:12:44 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56528/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:12:45 INFO - PROCESS | 1928 | RSS: Main: 257462272 08:12:45 INFO - PROCESS | 1928 | 08:12:47 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:12:47 INFO - PROCESS | 1928 | RSS: Main: 257531904 08:12:47 INFO - PROCESS | 1928 | 08:12:50 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:12:50 INFO - PROCESS | 1928 | RSS: Main: 257556480 08:12:50 INFO - PROCESS | 1928 | 08:12:53 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:12:54 INFO - PROCESS | 1928 | RSS: Main: 257818624 08:12:54 INFO - PROCESS | 1928 | 08:12:57 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:12:58 INFO - PROCESS | 1928 | RSS: Main: 266084352 08:12:58 INFO - PROCESS | 1928 | 08:12:59 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56528/tests/kraken/driver.html?testName=ai-astar) 08:13:00 INFO - PROCESS | 1928 | RSS: Main: 267218944 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | __start_tp_report 08:13:00 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 08:13:00 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 08:13:00 INFO - PROCESS | 1928 | |i|pagename|runs| 08:13:00 INFO - PROCESS | 1928 | |0;ai-astar;106;120;117;116;119;117;122;120;119;119 08:13:00 INFO - PROCESS | 1928 | |1;audio-beat-detection;139;112;99;177;102;136;141;144;144;95 08:13:00 INFO - PROCESS | 1928 | |2;audio-dft;196;200;201;194;201;198;194;201;202;195 08:13:00 INFO - PROCESS | 1928 | |3;audio-fft;98;82;84;104;62;63;76;83;91;52 08:13:00 INFO - PROCESS | 1928 | |4;audio-oscillator;66;101;125;72;70;72;86;69;69;88 08:13:00 INFO - PROCESS | 1928 | |5;imaging-gaussian-blur;90;87;91;113;123;125;132;87;89;88 08:13:00 INFO - PROCESS | 1928 | |6;imaging-darkroom;107;100;103;100;96;165;150;118;156;98 08:13:00 INFO - PROCESS | 1928 | |7;imaging-desaturate;90;100;79;134;139;89;90;90;92;87 08:13:00 INFO - PROCESS | 1928 | |8;json-parse-financial;55;57;57;58;63;64;60;59;59;60 08:13:00 INFO - PROCESS | 1928 | |9;json-stringify-tinderbox;72;68;71;78;73;70;70;72;72;80 08:13:00 INFO - PROCESS | 1928 | |10;stanford-crypto-aes;95;72;88;100;113;106;85;84;86;87 08:13:00 INFO - PROCESS | 1928 | |11;stanford-crypto-ccm;142;149;156;152;142;144;140;178;191;169 08:13:00 INFO - PROCESS | 1928 | |12;stanford-crypto-pbkdf2;173;174;173;167;175;184;237;174;233;244 08:13:00 INFO - PROCESS | 1928 | |13;stanford-crypto-sha256-iterative;70;67;70;70;68;67;66;69;60;81 08:13:00 INFO - PROCESS | 1928 | __end_tp_report 08:13:00 INFO - PROCESS | 1928 | __start_cc_report 08:13:00 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,991 08:13:00 INFO - PROCESS | 1928 | __end_cc_report 08:13:00 INFO - PROCESS | 1928 | __startTimestamp1482941580206__endTimestamp 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | ------- Summary: start ------- 08:13:00 INFO - PROCESS | 1928 | Number of tests: 14 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | [#0] ai-astar Cycles:10 Average:117.50 Median:119.00 stddev:4.40 (3.7%) stddev-sans-first:1.86 08:13:00 INFO - PROCESS | 1928 | Values: 106.0 120.0 117.0 116.0 119.0 117.0 122.0 120.0 119.0 119.0 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | [#1] audio-beat-detection Cycles:10 Average:128.90 Median:144.00 stddev:26.08 (18.1%) stddev-sans-first:27.40 08:13:00 INFO - PROCESS | 1928 | Values: 139.0 112.0 99.0 177.0 102.0 136.0 141.0 144.0 144.0 95.0 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | [#2] audio-dft Cycles:10 Average:198.20 Median:200.50 stddev:3.19 (1.6%) stddev-sans-first:3.28 08:13:00 INFO - PROCESS | 1928 | Values: 196.0 200.0 201.0 194.0 201.0 198.0 194.0 201.0 202.0 195.0 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | [#3] audio-fft Cycles:10 Average:79.50 Median:82.50 stddev:16.53 (20.0%) stddev-sans-first:16.13 08:13:00 INFO - PROCESS | 1928 | Values: 98.0 82.0 84.0 104.0 62.0 63.0 76.0 83.0 91.0 52.0 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | [#4] audio-oscillator Cycles:10 Average:81.80 Median:71.00 stddev:18.86 (26.6%) stddev-sans-first:19.11 08:13:00 INFO - PROCESS | 1928 | Values: 66.0 101.0 125.0 72.0 70.0 72.0 86.0 69.0 69.0 88.0 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | [#5] imaging-gaussian-blur Cycles:10 Average:102.50 Median:87.50 stddev:18.46 (21.1%) stddev-sans-first:19.02 08:13:00 INFO - PROCESS | 1928 | Values: 90.0 87.0 91.0 113.0 123.0 125.0 132.0 87.0 89.0 88.0 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | [#6] imaging-darkroom Cycles:10 Average:119.30 Median:153.00 stddev:26.95 (17.6%) stddev-sans-first:28.22 08:13:00 INFO - PROCESS | 1928 | Values: 107.0 100.0 103.0 100.0 96.0 165.0 150.0 118.0 156.0 98.0 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | [#7] imaging-desaturate Cycles:10 Average:99.00 Median:89.50 stddev:20.45 (22.8%) stddev-sans-first:21.42 08:13:00 INFO - PROCESS | 1928 | Values: 90.0 100.0 79.0 134.0 139.0 89.0 90.0 90.0 92.0 87.0 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | [#8] json-parse-financial Cycles:10 Average:59.20 Median:59.50 stddev:2.74 (4.6%) stddev-sans-first:2.45 08:13:00 INFO - PROCESS | 1928 | Values: 55.0 57.0 57.0 58.0 63.0 64.0 60.0 59.0 59.0 60.0 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | [#9] json-stringify-tinderbox Cycles:10 Average:72.60 Median:72.00 stddev:3.69 (5.1%) stddev-sans-first:3.91 08:13:00 INFO - PROCESS | 1928 | Values: 72.0 68.0 71.0 78.0 73.0 70.0 70.0 72.0 72.0 80.0 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:85.50 stddev:12.01 (14.0%) stddev-sans-first:12.68 08:13:00 INFO - PROCESS | 1928 | Values: 95.0 72.0 88.0 100.0 113.0 106.0 85.0 84.0 86.0 87.0 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | [#11] stanford-crypto-ccm Cycles:10 Average:156.30 Median:154.00 stddev:17.43 (11.3%) stddev-sans-first:17.70 08:13:00 INFO - PROCESS | 1928 | Values: 142.0 149.0 156.0 152.0 142.0 144.0 140.0 178.0 191.0 169.0 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.40 Median:179.50 stddev:31.16 (17.4%) stddev-sans-first:32.16 08:13:00 INFO - PROCESS | 1928 | Values: 173.0 174.0 173.0 167.0 175.0 184.0 237.0 174.0 233.0 244.0 08:13:00 INFO - PROCESS | 1928 | 08:13:00 INFO - PROCESS | 1928 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.50 stddev:5.22 (7.5%) stddev-sans-first:5.52 08:13:00 INFO - PROCESS | 1928 | Values: 70.0 67.0 70.0 70.0 68.0 67.0 66.0 69.0 60.0 81.0 08:13:00 INFO - PROCESS | 1928 | -------- Summary: end -------- 08:13:00 INFO - PROCESS | 1928 | 08:13:01 INFO - TEST-INFO | 1928: exit 0 08:13:01 INFO - TEST-OK | kraken | took 61465ms 08:13:01 INFO - SUITE-END | took 1177s 08:13:01 INFO - Completed test suite (00:19:37) 08:13:01 INFO - javascript benchmark 08:13:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7018.39610190488, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2448.68167138272, 2854.7430731234185, 3039.90563397177, 3379.766385269377, 3434.2272988577574, 5277.261183434322, 5798.797042103527, 5807.506361959233, 5844.678542680156, 5854.946109700252, 4845.861222612683, 5865.665081711752, 5937.22816033915, 5938.940610593892, 5987.940120598792, 4998.325226094476, 5323.387810401795, 5363.973180134094, 5382.138857782756, 5417.485338892819, 5400.945990540084, 5416.21464887593, 5458.918116228259, 5462.808801691942, 5489.835304940838, 5249.580033597303, 5281.0, 5406.729663516828, 5414.40441551429, 5423.83728488146, 5208.348956380452, 5309.761060752276, 5340.546053585431, 5343.038253114438, 5383.703896285702, 4349.238883195429, 5003.924941125886, 5281.260623512711, 5317.734113294358, 5456.399796022434, 5942.851428714273, 5992.730327135289, 6009.699515024275, 6014.669193194372, 6049.788257411014, 6128.448439640454, 6289.842753931142, 6306.211723534559, 6356.872862542769, 6362.809115726535, 6167.044108163242, 6223.906641400383, 6247.718852651642, 6259.311475737665, 6281.434670879643, 6007.189029481052, 6008.36912124225, 6019.638821670714, 6020.217371741689, 6050.092486127072, 6429.32492088324, 6450.387213214737, 6470.741170353133, 6479.6760161991715, 6503.512236582275, 6129.785457508966, 6146.600470969463, 6159.784407545804, 6231.937680623227, 6298.874022519524, 6237.563370564108, 6243.250809902841, 6259.499240060784, 6270.7491700331475, 6353.5234857385885, 6254.937450625526, 6270.278917924497, 6315.747370105236, 6318.210223722035, 6320.968395157995, 6240.375962403723, 6282.905756413657, 6309.211348581427, 6319.842003949846, 6330.778422755181, 4895.7062576244825, 4940.283658869444, 4956.8760780980765, 4963.652544321935, 5097.490250974873, 4477.19410506112, 4480.731156130578, 4511.390962220058, 4534.546545345439, 4538.387317712132, 4663.510331415153, 5937.139114828326, 5981.910271345933, 5993.520518358521, 6007.038873780174, 6211.689415529206, 6238.376162383816, 6274.403931626488, 6297.338779428094, 6354.650494222862, 5882.970585147047, 5968.045112781934, 5982.192404025488, 5999.0, 6021.096835474714, 5355.3305836770405, 5411.729413529308, 5412.07994640912, 5486.423925487767, 5523.28197334344, 6370.74517019314, 6458.806235812933, 6459.709313080829, 6474.384933431316, 6486.805395838132, 6213.1922850029205, 6324.177856878577, 6330.651814150266, 6331.556791024584, 6346.2067241594805, 6340.207474065742, 6369.8726025480155, 6413.775517856852, 6416.262129855127, 6522.543421960607, 6291.748330066746, 6297.685115744102, 6309.148264984168, 6309.927312356818, 6316.3367846377305, 6322.841928951629, 6328.588641738273, 6350.0, 6351.555391122762, 6353.650549219838, 4680.368150299802, 5327.786888524415, 6110.602810817282, 6118.326984031842, 6130.428711073822, 6093.116498107661, 6164.8767024658355, 6176.629402235881, 6198.132261483306, 6291.182146321132, 5508.732991412078, 5765.596408251382, 6127.816165515042, 6165.537584681257, 6187.876242475215, 4947.554720075211, 5408.0, 6155.322914479314, 6202.069689546604, 6216.564840461305, 4385.276429389115, 4914.115459217375, 5067.721275329893, 5516.586256030733, 5661.858453538694], "value": 5719.0455051053, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.8845900065986, 479.9280107983803, 483.59619717535855, 497.3484734997153, 522.6132661830244, 32152.0, 34292.628294868235, 38527.0, 38856.222875542546, 40200.0, 33390.33219335612, 40128.59871401285, 40316.596834031654, 40576.59423405765, 42913.92715182122, 7651.846963060735, 9226.861597076017, 9343.018983006788, 9373.906260937405, 9383.81232375352, 9274.0, 9288.535573221347, 9418.5761640726, 9492.050794920488, 9498.572564234591, 9490.383125096881, 9538.0, 9539.80920381592, 9550.856737148948, 9569.904300957005, 9456.432614043146, 9461.810763784686, 9512.191463725592, 9515.381500202464, 9520.571574279105, 9253.259739220914, 9287.58205880737, 9329.16037556623, 9354.953225233898, 9355.719328420158, 38985.805070974464, 39209.41185882214, 39218.0, 39371.40942885859, 39688.0, 3270.2968861694612, 3299.191698033973, 3349.69852713257, 3480.5301284326547, 3759.812009399519, 28096.297592560146, 28689.28276793097, 29295.853520732475, 30787.076387708406, 31114.222144446343, 2622.8295160814487, 3112.097491727396, 3150.5116706910235, 3231.83840807961, 3252.674732526752, 346063.9999999975, 348474.0, 349058.0, 349792.25103874574, 352585.0, 58290.0, 58606.0, 58630.413695862495, 58858.70570647119, 58929.0, 59969.10046349308, 60221.0, 60468.0, 60508.0, 60959.78080438455, 15605.843941560666, 15680.76478852818, 15842.128682922319, 15865.682686346208, 15872.968257063254, 12246.938765306117, 12258.957988571072, 12288.508459661693, 12307.953823924887, 12346.197497162835, 15382.538523844303, 15779.211039448212, 15784.368625254861, 15834.60413489672, 15864.0, 3196.2488815128427, 6586.50601204902, 6774.45804335652, 6777.2545020047755, 6797.2523022467485, 849.2950850793826, 945.628838184635, 963.393062370702, 965.9855102173473, 1034.8447732840134, 29058.7094129056, 29558.852205738833, 29788.0, 29874.0, 30291.091267262018, 1040.8907064758243, 1068.8824229334766, 1112.4993752811267, 1115.939857135708, 1121.360824330124, 12689.428975696115, 13680.384382702803, 13908.860911390757, 14926.626834329229, 15300.770488442682, 622674.0, 641566.0, 680122.0, 681636.0, 681689.0, 56110.0, 56516.739330426324, 56709.7164514183, 56766.0, 56910.0, 1343.657367371314, 1363.7749771287824, 1380.6341319550327, 1383.100984360174, 1391.1775573997893, 28400.43199136047, 29179.124626261248, 29200.853995729885, 29209.269768255963, 29486.852565737892, 40918.18163636651, 46833.531664682916, 48876.02247955092, 48892.75553622209, 49520.75239623779, 606352.0, 609455.0, 610656.0, 611392.0, 611670.0, 22754.976026078533, 24900.501989960463, 25027.0, 25189.24432267035, 25220.24339269825, 43527.564724353615, 43991.78004109959, 44349.0, 45634.63096107122, 45938.77030614825, 28590.714092858805, 32096.67903320937, 35836.566537338214, 37163.0, 37361.4395784058, 37273.440898386005, 38063.61936380711, 38116.42825357677, 38307.977662346166, 38789.0, 36905.26189476142, 37031.814840925625, 37039.81480092582, 37043.44434833534, 38022.61977380191, 31772.205694857814, 33768.3246335067, 34244.657553425146, 34405.0, 34431.13922151966, 25278.736063197135, 25327.62008569836, 25389.619155713048, 25711.74288257168, 26060.34849128787], "value": 19058.176720952953, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.3351017902507, 366.4924080148993, 378.4096808977996, 378.94505296731984, 396.0929471510242, 18527.907360463196, 18568.35010774623, 18828.81171188291, 19126.904365478153, 19311.903440482813, 11427.828582571246, 11687.0, 11827.763444731101, 11831.881681183186, 11834.526618935231, 4909.459959404463, 4964.478729733371, 5199.610029247821, 5350.117230656937, 5423.322084739408, 3835.61643835617, 4026.6979976501734, 5294.788208471657, 5671.177679236508, 5955.076963070731, 6006.489448396892, 6016.0, 6036.15493830864, 6051.878962420749, 6053.697315134248, 5774.133879918004, 5985.970070149643, 6039.849003774897, 6043.8186854394435, 6075.210222671069, 5597.468240517144, 5777.711114444261, 5844.853878653025, 5855.677937713424, 5873.089671100986, 12963.15739476931, 13004.804927926089, 13011.154274972096, 13022.609321720363, 15461.92269038659, 2173.739151301854, 2284.920027799036, 2358.445765245166, 2567.717551069381, 2785.832850029005, 9559.22455253114, 9605.567749451291, 10338.534765935476, 10721.0, 15761.448349307719, 1582.485692150056, 1697.73685078813, 1866.2348437140843, 1878.4552479780848, 1967.3802752133033, 90959.0, 104762.95237047608, 118775.40612296884, 124640.37679811542, 125610.0, 11070.225084244186, 11108.389038602792, 11386.316820990767, 11445.599404020819, 11629.1859569831, 14574.19841908684, 14602.561923142322, 14619.853801462063, 14635.92682036583, 14640.267986600627, 8304.418690691593, 8503.532305723245, 8534.658613655509, 8561.700340488052, 8643.740687779376, 7011.614361210182, 8328.375371847136, 8376.246137847624, 8477.406581539357, 8536.8719469208, 3741.607131251234, 4518.548145185455, 4607.285870690049, 4624.491305956342, 4678.578927896506, 1424.3519198764536, 1584.3662534986097, 1661.2856471717037, 1916.3963351544219, 1964.6168997045438, 825.1748251748252, 876.5748611923212, 884.3102380143498, 908.4268015723584, 955.1308309438377, 19588.412347629594, 20331.597725387503, 20423.795762042188, 20485.2830150944, 20793.272235471988, 895.901450840407, 910.2490445382585, 915.7481692534607, 943.9386439881523, 997.2221667099674, 6680.432163266079, 10772.622958196425, 11299.717507062225, 11782.705432364088, 11814.88185118155, 603987.0, 614354.7129057483, 615779.0, 622363.0, 626182.0, 125100.1234981476, 125336.0, 127773.36113319374, 131636.0, 132853.0, 960.2653969713175, 979.4172467381896, 999.4558407260799, 1003.8694969653898, 1004.3321191407632, 16435.26041328119, 17847.37534186297, 18936.810631893506, 18972.146253418352, 19138.947357894893, 47990.0, 48347.0, 49861.002779943476, 50139.0, 50165.74585635388, 74215.62892185505, 76261.0, 77892.0, 78392.60803695946, 79105.0, 8005.599720014093, 8037.115917249215, 8265.214804593554, 8297.502149871027, 8407.95796021016, 11211.831822522505, 12328.445219965302, 12460.750784984431, 12617.747645047231, 12877.291748953898, 8260.173982601691, 8798.956005219932, 9137.583674530459, 10438.164946804085, 10816.891831081588, 8814.955925220333, 9841.81898172224, 10450.895491045296, 10583.576656933636, 10791.946040269748, 5984.521238300838, 10559.472026398496, 10855.023047925724, 10901.618443354444, 11039.834402483808, 6954.0, 8078.6364613593705, 8108.472949258279, 8186.672533098609, 8294.046184688837, 9447.096658210841, 10193.133583645176, 10501.632442864464, 10820.296680715728, 10893.0], "value": 8994.764034535954, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1435.7508967198535, 1463.5975106845617, 1569.952901412958, 1609.9678006439872, 1732.5408766676833, 7509.812254693636, 7741.7677469675855, 7998.1601931797195, 8444.324454043677, 8452.8732069019, 7908.0905695844995, 8230.176982301768, 8230.794230144249, 8263.793405164874, 8274.62764175612, 2277.954440911181, 2297.850639708422, 2328.639060945556, 2331.6619090231907, 2337.0, 2276.2829708641807, 2343.484433424644, 2361.7992470640015, 2364.054378248697, 2366.7869891709743, 1919.8560107991887, 2310.260716570698, 2322.2336628912417, 2331.4521087544413, 2337.859728416292, 1933.2460340467228, 2288.336382449088, 2297.253392647396, 2309.0532881518666, 2313.8611683299055, 2337.5558643857785, 2344.0858065354523, 2348.9177878774162, 2349.8707571083582, 2373.8931748071377, 8246.505209687439, 8248.46384984974, 8335.624896879655, 8337.95831020847, 8403.495790252604, 5063.367079115111, 5208.713520756357, 5212.791488340462, 5229.973850130763, 5241.73791310433, 2632.93417664558, 3140.795848269878, 3434.072800343918, 3434.8282585870606, 3443.2941247044296, 1008.1078245752489, 1042.989570104297, 1044.2742294105599, 1056.3186744549798, 1056.7410984308895, 10456.215783816244, 10590.311629744043, 10725.14198864089, 10756.73108172294, 10919.617813376493, 3962.484876965976, 4309.073549186877, 4429.269170586882, 4674.439067311876, 4709.576138147515, 8322.16778322163, 8355.832883342298, 8360.540170290571, 8366.665333386718, 8376.748697539084, 4884.755762211875, 4885.316055752198, 4894.070126675921, 4906.533879281463, 4909.337239472697, 4253.468316460442, 4378.365137055109, 4501.279795232813, 4575.10785396851, 4707.552782485727, 3827.7129215308964, 3843.36584463566, 3906.9609303907164, 3930.4890364252465, 3942.9211415771524, 1250.3123282194938, 1269.51123817331, 1277.2974863824859, 1289.8258735070644, 1290.2452065541763, 790.0440467034952, 845.9323254139654, 863.3222920007764, 867.6052396159859, 892.1791951404723, 5400.432954539795, 5481.479259470344, 5483.052202414256, 5493.450654934474, 5566.66600003993, 432.2932006169935, 432.36010704158025, 435.9716618419856, 762.9732959346396, 906.5331354352513, 3132.5144602586633, 3322.501624756306, 3435.587729472479, 3661.8901432957055, 3732.104294969188, 9708.271879609058, 10164.0, 10176.185905127571, 10181.0, 10225.539850706737, 8983.775405614912, 9244.399114057565, 9689.51552422376, 9875.851862222073, 9942.552585133542, 6268.780592679234, 7366.594837284001, 7873.1733168016535, 7874.409419293576, 7875.527468351917, 4423.358613001161, 5259.526642602184, 5260.736963151904, 5531.363893152178, 5549.667019978814, 8983.371164018456, 9241.907580924104, 9344.252459803336, 9388.342816002814, 9429.52852357393, 9157.130072643089, 9287.860682089638, 9471.337006409485, 9603.711888643353, 9608.039196080335, 7512.060992375953, 7707.961460192663, 7726.652300646597, 7833.647485863035, 7872.84254314922, 7946.284834364935, 7964.482308649919, 7965.163657816078, 8020.719274825353, 8059.3552515799765, 7100.218975912749, 7111.359977602041, 7127.501074924913, 7176.96411517939, 7193.964030179815, 6865.41643960249, 6959.512834101564, 6967.1291088613925, 7090.787276381717, 7126.5011449198055, 5579.302587176603, 6361.332060133619, 6541.476681865534, 6558.967205163944, 6576.0, 4622.353333964487, 4777.546133117348, 4813.663043586916, 4848.903021939471, 4896.996115796324, 4721.220998535203, 4722.126406614787, 4822.68652537584, 4851.078295123915, 4933.802647894044], "value": 4287.793231070634, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.1530024130985, 302.1931443047065, 309.12363449620324, 309.3349299007135, 312.38927895963394, 5219.686818790875, 5323.657590660112, 5403.972980135104, 5472.26124473196, 5481.588880833939, 4238.766867822269, 5311.954897364513, 5356.3572371315395, 5372.0, 5385.192221166827, 3610.4042832932537, 4119.649829764466, 4136.048708796979, 4142.032534546097, 4149.875503734893, 2905.331773692052, 4032.5362583303004, 4093.897652558695, 4135.60711732385, 4169.437125987984, 3619.963800362002, 3862.9806850965706, 3880.417937309412, 4080.1227736036744, 4116.855910043149, 3546.840392182345, 3975.70182236332, 4113.938290925638, 4116.526599441073, 4120.114175452292, 2993.925151871199, 3574.6782789549065, 3596.0512379848533, 3603.657652523006, 3612.187257866985, 4021.9798901005306, 4935.506449355072, 5180.948190518085, 5258.421573626898, 5258.684478931238, 3890.1247219375414, 4203.600657937491, 4659.324397962277, 4716.490722968651, 4723.173444647173, 4607.65944690097, 5596.916046259309, 5633.521150702195, 5639.210510528529, 5643.294588176478, 3649.1059690375764, 3777.8299976501125, 3942.2903877302074, 3954.782486963216, 3983.521977362706, 5972.923944168757, 5991.191189189491, 5992.550558708071, 6002.309734380515, 6031.577789554733, 3999.560048394674, 4021.959780402217, 4028.033272014754, 4041.6160464755803, 4053.8175782089875, 3510.929781404409, 3657.2868290683064, 3845.9807700961874, 3882.9029274267805, 4104.343305071175, 3862.980685096613, 3945.7237993340764, 4576.382188404523, 4586.289125185602, 4602.562756538124, 4259.424977628042, 4299.634531064895, 5258.1849813279005, 5260.894782104337, 5687.146927960756, 1062.3148069495132, 1143.982840257397, 1184.8755880632582, 1262.2741923394085, 1281.0520215040804, 825.0594322472386, 845.9890430935096, 867.9324430949966, 872.3152325424511, 874.7725591346295, 4305.677074219446, 4360.803763830635, 4458.621017213508, 4499.955000450019, 4894.252575002242, 5464.863378415492, 5490.752916118784, 5516.668999859941, 5518.972405137949, 5542.889142217214, 4349.3041113421705, 4816.373871396766, 4836.250381190921, 5019.139090718329, 5029.84910452687, 2877.331402461836, 2887.7978541501893, 2888.234617826278, 2889.5954566360724, 2927.238917881366, 4967.453580106186, 5226.425093239741, 5379.224855797577, 5689.573282003867, 5764.538836893039, 5035.169197082514, 5344.866378340573, 5568.635684257302, 5622.409646987089, 5658.462446067617, 5703.657780533182, 5708.40061793514, 5717.998051023246, 5718.768895573988, 5720.227769251181, 5171.198464238049, 5441.646292990943, 5485.177223416519, 5488.396276409671, 5566.666000040011, 4459.9038451927545, 4677.976610116928, 5021.0, 5422.403535611001, 5453.427390123979, 5305.965336759295, 5735.426457354231, 5745.798897038583, 5748.425157484218, 5774.711264436845, 3126.609173853268, 3675.9965818628375, 3676.7977761223387, 3737.6449237322413, 3835.0028992461603, 5271.1302635064785, 5316.760745766527, 5338.786448542015, 5462.962037212917, 5487.149491828773, 5062.974685126551, 5305.07161246788, 5377.516023557899, 5396.433374495621, 5571.749271282721, 5191.07930065878, 5655.0, 5663.762813688488, 5682.545396368363, 5692.886142277049, 4749.833755818529, 4904.681195722266, 5351.384590772111, 5521.549031855091, 5534.861628459321, 5636.1827535008015, 5649.378568357395, 5652.3217213934595, 5684.516816070515, 5753.165790960371, 4708.458527269408, 5169.327987361619, 5185.533302002837, 5577.637453565505, 5586.692731899784], "value": 4011.6873305340428, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.6318414175283, 699.3635791429798, 721.588694444167, 722.2993696114769, 729.4383324839873, 13981.30093495325, 14838.703225935475, 14872.0, 14876.702465950675, 14939.626509337271, 11017.559297628106, 13288.13627114237, 13326.0, 13362.933185334085, 13394.59816205513, 642.8135840606219, 664.0901964308904, 710.2932582080837, 789.8736202207648, 829.2371018662844, 802.3701394405393, 820.0774129104757, 829.6059371798408, 838.7525679924437, 839.870374346503, 877.534906499553, 882.3823323673422, 883.1609970527992, 884.4251236696167, 885.0706757904208, 857.5583574459127, 871.4161511787117, 871.9128087191261, 872.4198408058634, 872.8341615093112, 639.6769631336145, 655.1810237203496, 655.1810237203496, 656.1044174701545, 657.135866335767, 13262.270575118364, 13417.66455838612, 13600.795988060187, 13611.11527750693, 14170.504032358818, 13194.274314912676, 13360.265185414799, 13405.932970335183, 13616.86383136166, 13723.284589426326, 10727.409992450412, 10905.890941090567, 10967.780644387067, 11048.116150707923, 11214.719632009184, 3527.576690797095, 3612.4581312803025, 3641.890743277706, 3670.082479380155, 3673.3204357194004, 85002.0, 85373.0, 86228.27543449159, 86757.56621216917, 87410.56294718549, 9596.656468094472, 10281.485925703684, 10346.22403319754, 10397.012283833024, 10405.739856503647, 18324.81675183258, 20154.09306581178, 20272.0, 20367.4908127298, 20732.0, 6337.76413599353, 6718.395344418928, 7305.3425191732995, 7434.516756410816, 7566.35685966688, 4100.856470023595, 4151.7924103794685, 4227.175700738388, 4240.745555266694, 4294.6993710439965, 3225.2420681139915, 3289.5723555937575, 3305.3554556861664, 3331.2338162222823, 3378.983105084508, 597.4644914863791, 603.667982609563, 608.1546650156287, 616.6854903998993, 618.7617767159586, 586.2115454713404, 591.4942723971029, 609.6464050850497, 640.3788325324427, 642.6722371590522, 6847.637565040053, 8401.36989725773, 8671.6531338747, 8991.865122023175, 9069.460551045719, 509.77824646278987, 550.6310771782915, 554.3541773833439, 640.1262276991965, 661.024988142486, 4266.722663026893, 4435.223835828717, 4444.799984000728, 4495.280755079237, 4517.119161763425, 79170.0, 89051.0, 115245.0, 119473.0, 120263.797362027, 95632.52183739176, 95700.0, 106340.46829765956, 107224.46387768167, 108619.0, 55611.721941390846, 64534.354656453776, 88663.55668221618, 93277.5336123315, 95507.56738648926, 9153.816923661623, 9596.424214547149, 9746.512674366395, 9801.11789938909, 9801.166900813225, 41483.792581036905, 43130.78434607807, 46605.53394466012, 51459.48540514547, 53921.0, 45818.770906145255, 52284.0, 65167.674161628885, 66755.66622166858, 70918.64540677263, 11853.644390668294, 11905.345206013752, 11973.76052478963, 12167.878321216675, 12205.145639805129, 13575.185488870698, 13902.51341203053, 13964.650883727989, 14147.575572732834, 14309.499167529086, 12110.576129835767, 13166.40751166219, 13228.140170889246, 13433.798493022417, 13632.113912595649, 12589.87410125887, 13032.218066916015, 13199.868001319865, 13201.735965280832, 13336.599902002956, 12505.93747031259, 12637.115401921777, 12749.043821713223, 13080.28058456794, 13153.60539183826, 9457.19613832832, 10304.536295866554, 10973.945130274617, 10992.230543861853, 11035.83446248323, 10852.294600850919, 10943.45282735876, 11153.609623663133, 11188.272762270426, 11258.718532036437], "value": 7086.391049455758, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1568.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 120.0, 117.0, 116.0, 119.0, 117.0, 122.0, 120.0, 119.0, 119.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 112.0, 99.0, 177.0, 102.0, 136.0, 141.0, 144.0, 144.0, 95.0], "value": 128.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 200.0, 201.0, 194.0, 201.0, 198.0, 194.0, 201.0, 202.0, 195.0], "value": 198.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 82.0, 84.0, 104.0, 62.0, 63.0, 76.0, 83.0, 91.0, 52.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 101.0, 125.0, 72.0, 70.0, 72.0, 86.0, 69.0, 69.0, 88.0], "value": 81.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 113.0, 123.0, 125.0, 132.0, 87.0, 89.0, 88.0], "value": 102.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 100.0, 103.0, 100.0, 96.0, 165.0, 150.0, 118.0, 156.0, 98.0], "value": 119.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 100.0, 79.0, 134.0, 139.0, 89.0, 90.0, 90.0, 92.0, 87.0], "value": 99.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 57.0, 58.0, 63.0, 64.0, 60.0, 59.0, 59.0, 60.0], "value": 59.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 71.0, 78.0, 73.0, 70.0, 70.0, 72.0, 72.0, 80.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 72.0, 88.0, 100.0, 113.0, 106.0, 85.0, 84.0, 86.0, 87.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 149.0, 156.0, 152.0, 142.0, 144.0, 140.0, 178.0, 191.0, 169.0], "value": 156.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 173.0, 167.0, 175.0, 184.0, 237.0, 174.0, 233.0, 244.0], "value": 193.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 70.0, 70.0, 68.0, 67.0, 66.0, 69.0, 60.0, 81.0], "value": 68.8, "unit": "score"}]}]} 08:13:01 INFO - Return code: 0 08:13:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:13:01 ERROR - # TBPL SUCCESS # 08:13:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:13:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:13:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:13:01 INFO - 2016-12-28 08:13:01.485 screenresolution[1933:22707] starting screenresolution argv=screenresolution get 08:13:01 INFO - 2016-12-28 08:13:01.489 screenresolution[1933:22707] Display 0: 1600x1200x32@60 08:13:01 INFO - 2016-12-28 08:13:01.495 screenresolution[1934:22710] starting screenresolution argv=screenresolution list 08:13:01 INFO - Available Modes on Display 0 08:13:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:13:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:13:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:13:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:13:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:13:01 INFO - Intel Iris: 08:13:01 INFO - Chipset Model: Intel Iris 08:13:01 INFO - Type: GPU 08:13:01 INFO - Bus: Built-In 08:13:01 INFO - VRAM (Dynamic, Max): 1536 MB 08:13:01 INFO - Vendor: Intel (0x8086) 08:13:01 INFO - Device ID: 0x0a2e 08:13:01 INFO - Revision ID: 0x0009 08:13:01 INFO - Displays: 08:13:01 INFO - Display: 08:13:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:13:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:13:01 INFO - Display Serial Number: 1600x1200 60 08:13:01 INFO - Main Display: Yes 08:13:01 INFO - Mirror: Off 08:13:01 INFO - Online: Yes 08:13:01 INFO - Rotation: Supported 08:13:01 INFO - Return code: 0 08:13:01 INFO - Running post-action listener: _resource_record_post_action 08:13:01 INFO - [mozharness: 2016-12-28 16:13:01.738031Z] Finished run-tests step (success) 08:13:01 INFO - Running post-run listener: _resource_record_post_run 08:13:01 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 1332736; Write bytes: 663488512; Read time: 22; Write time: 1984 08:13:01 INFO - TinderboxPrint: CPU usage
54.4% 08:13:01 INFO - TinderboxPrint: I/O read bytes / time
1,332,736 / 22 08:13:01 INFO - TinderboxPrint: I/O write bytes / time
663,488,512 / 1,984 08:13:01 INFO - TinderboxPrint: CPU idle
2,208.1 (45.6%) 08:13:01 INFO - TinderboxPrint: CPU system
976.9 (20.2%) 08:13:01 INFO - TinderboxPrint: CPU user
1,652.8 (34.2%) 08:13:01 INFO - TinderboxPrint: Swap in / out
350,154,752 / 0 08:13:01 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344979968; Write bytes: 345581568; Read time: 27100; Write time: 682 08:13:01 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1332736; Write bytes: 311037952; Read time: 22; Write time: 1273 08:13:02 INFO - Running post-run listener: _upload_blobber_files 08:13:02 INFO - Blob upload gear active. 08:13:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:13:02 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 08:13:02 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'] 08:13:02 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 08:13:02 INFO - (blobuploader) - INFO - Open directory for files ... 08:13:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:13:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:13:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:13:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:13:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:13:03 INFO - (blobuploader) - INFO - Done attempting. 08:13:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:13:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:13:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:13:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:13:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:13:04 INFO - (blobuploader) - INFO - Done attempting. 08:13:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:13:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:13:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:13:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:13:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:13:07 INFO - (blobuploader) - INFO - Done attempting. 08:13:07 INFO - (blobuploader) - INFO - Iteration through files over. 08:13:07 INFO - Return code: 0 08:13:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:13:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:13:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40c46ecf611ab74bfe3aff6a2f2192ba77e644921ad3c3c0236aa18dc85e445f936cddbd085b010f010ce0a0c9c590f4fe9a678eb645b6ecd512b81ccec8c622", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d741226bc1fb2b91f15da2f2eae28c84c7eb20527066c1055392419805df5610ad489f87777edd2d54c4597697bfe90cbe1e3f63eb87dde02b6930cfa0fe8100", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13622429dde84368e8cc21dd974a2c627e5492f6e86f930b178758ac8014cc2efa90c35ceaba49dd41e3f8d764f37d198a0400c86789a9f3d10643b49662cba8"} 08:13:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:13:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:13:07 INFO - Contents: 08:13:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40c46ecf611ab74bfe3aff6a2f2192ba77e644921ad3c3c0236aa18dc85e445f936cddbd085b010f010ce0a0c9c590f4fe9a678eb645b6ecd512b81ccec8c622", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d741226bc1fb2b91f15da2f2eae28c84c7eb20527066c1055392419805df5610ad489f87777edd2d54c4597697bfe90cbe1e3f63eb87dde02b6930cfa0fe8100", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13622429dde84368e8cc21dd974a2c627e5492f6e86f930b178758ac8014cc2efa90c35ceaba49dd41e3f8d764f37d198a0400c86789a9f3d10643b49662cba8"} 08:13:07 INFO - Running post-run listener: copy_logs_to_upload_dir 08:13:07 INFO - Copying logs to upload dir... 08:13:07 INFO - mkdir: /builds/slave/test/build/upload/logs 08:13:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.490667 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-28 08:13:08.019874) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 08:13:08.023803) ========= 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.IRiVE7ay0a/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.KmKEYRK9I2/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40c46ecf611ab74bfe3aff6a2f2192ba77e644921ad3c3c0236aa18dc85e445f936cddbd085b010f010ce0a0c9c590f4fe9a678eb645b6ecd512b81ccec8c622", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d741226bc1fb2b91f15da2f2eae28c84c7eb20527066c1055392419805df5610ad489f87777edd2d54c4597697bfe90cbe1e3f63eb87dde02b6930cfa0fe8100", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13622429dde84368e8cc21dd974a2c627e5492f6e86f930b178758ac8014cc2efa90c35ceaba49dd41e3f8d764f37d198a0400c86789a9f3d10643b49662cba8"} build_url:https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011249 build_url: 'https://queue.taskcluster.net/v1/task/EdAhskubQ96W2ZbZJzYY6A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40c46ecf611ab74bfe3aff6a2f2192ba77e644921ad3c3c0236aa18dc85e445f936cddbd085b010f010ce0a0c9c590f4fe9a678eb645b6ecd512b81ccec8c622", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d741226bc1fb2b91f15da2f2eae28c84c7eb20527066c1055392419805df5610ad489f87777edd2d54c4597697bfe90cbe1e3f63eb87dde02b6930cfa0fe8100", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13622429dde84368e8cc21dd974a2c627e5492f6e86f930b178758ac8014cc2efa90c35ceaba49dd41e3f8d764f37d198a0400c86789a9f3d10643b49662cba8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 08:13:08.058555) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 08:13:08.058875) ========= 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.IRiVE7ay0a/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.KmKEYRK9I2/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.005244 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 08:13:08.107267) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 08:13:08.107619) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 08:13:08.107931) ========= ========= Total master_lag: 0.22 =========