builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0384 starttime: 1482769485.41 results: success (0) buildid: 20161226064937 builduid: f70257de4fa54735ae58007535d91014 revision: 43763ed575bf103382d58e175e0ed8b90a83e874 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 08:24:45.408366) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 08:24:45.408803) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 08:24:45.409108) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 08:24:45.437624) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 08:24:45.438016) ========= 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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners TMPDIR=/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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-26 08:24:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 155M=0s 2016-12-26 08:24:46 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.601825 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 08:24:46.074444) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 08:24:46.074753) ========= 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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners TMPDIR=/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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.024531 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 08:24:46.123901) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 08:24:46.124266) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 43763ed575bf103382d58e175e0ed8b90a83e874 --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 43763ed575bf103382d58e175e0ed8b90a83e874 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DA3Lf5pwgi/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.GchF0pPrvf/Listeners TMPDIR=/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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-26 08:24:46,221 truncating revision to first 12 chars 2016-12-26 08:24:46,221 Setting DEBUG logging. 2016-12-26 08:24:46,221 attempt 1/10 2016-12-26 08:24:46,221 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/43763ed575bf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-26 08:24:47,452 unpacking tar archive at: mozilla-aurora-43763ed575bf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.520023 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 08:24:47.667702) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 08:24:47.668088) ========= script_repo_revision: 43763ed575bf103382d58e175e0ed8b90a83e874 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 08:24:47.668520) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 08:24:47.668787) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 08:24:47.688385) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-26 08:24:47.688788) ========= /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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners TMPDIR=/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 08:24:47 INFO - MultiFileLogger online at 20161226 08:24:47 in /builds/slave/test 08:24:47 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 08:24:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:24:47 INFO - {'append_to_log': False, 08:24:47 INFO - 'base_work_dir': '/builds/slave/test', 08:24:47 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:24:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:24:47 INFO - 'branch': 'Mozilla-Aurora', 08:24:47 INFO - 'buildbot_json_path': 'buildprops.json', 08:24:47 INFO - 'config_files': ('talos/mac_config.py',), 08:24:47 INFO - 'default_actions': ('clobber', 08:24:47 INFO - 'read-buildbot-config', 08:24:47 INFO - 'download-and-extract', 08:24:47 INFO - 'populate-webroot', 08:24:47 INFO - 'create-virtualenv', 08:24:47 INFO - 'install', 08:24:47 INFO - 'run-tests'), 08:24:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:24:47 INFO - 'download_minidump_stackwalk': True, 08:24:47 INFO - 'download_symbols': 'ondemand', 08:24:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:24:47 INFO - 'tooltool.py': '/tools/tooltool.py', 08:24:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:24:47 INFO - '/tools/misc-python/virtualenv.py')}, 08:24:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:24:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:24:47 INFO - 'installer_path': 'installer.exe', 08:24:47 INFO - 'log_level': 'info', 08:24:47 INFO - 'log_name': 'talos', 08:24:47 INFO - 'log_to_console': True, 08:24:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:24:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:24:47 INFO - 'opt_config_files': (), 08:24:47 INFO - 'pip_index': False, 08:24:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:24:47 INFO - 'cmd': ('bash', 08:24:47 INFO - '-c', 08:24:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:24:47 INFO - 'enabled': True, 08:24:47 INFO - 'halt_on_failure': False, 08:24:47 INFO - 'name': 'check_screen_resolution'},), 08:24:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:24:47 INFO - 'cmd': ('bash', 08:24:47 INFO - '-c', 08:24:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:24:47 INFO - 'enabled': True, 08:24:47 INFO - 'halt_on_failure': False, 08:24:47 INFO - 'name': 'check_screen_resolution'},), 08:24:47 INFO - 'run_cmd_checks_enabled': True, 08:24:47 INFO - 'sps_profile': False, 08:24:47 INFO - 'sps_profile_interval': 0, 08:24:47 INFO - 'suite': 'dromaeojs-e10s', 08:24:47 INFO - 'system_bits': '32', 08:24:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:24:47 INFO - 'title': 't-yosemite-r7-0384', 08:24:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:24:47 INFO - 'use_talos_json': True, 08:24:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:24:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:24:47 INFO - 'work_dir': 'build'} 08:24:47 INFO - [mozharness: 2016-12-26 16:24:47.892733Z] Running clobber step. 08:24:47 INFO - Running pre-action listener: _resource_record_pre_action 08:24:47 INFO - Running main action method: clobber 08:24:47 INFO - rmtree: /builds/slave/test/build 08:24:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:24:50 INFO - Running post-action listener: _resource_record_post_action 08:24:50 INFO - [mozharness: 2016-12-26 16:24:50.309324Z] Finished clobber step (success) 08:24:50 INFO - [mozharness: 2016-12-26 16:24:50.309573Z] Running read-buildbot-config step. 08:24:50 INFO - Running pre-action listener: _resource_record_pre_action 08:24:50 INFO - Running main action method: read_buildbot_config 08:24:50 INFO - Using buildbot properties: 08:24:50 INFO - { 08:24:50 INFO - "project": "", 08:24:50 INFO - "product": "firefox", 08:24:50 INFO - "script_repo_revision": "production", 08:24:50 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:24:50 INFO - "repository": "", 08:24:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:24:50 INFO - "buildid": "20161226064937", 08:24:50 INFO - "pgo_build": "False", 08:24:50 INFO - "basedir": "/builds/slave/test", 08:24:50 INFO - "buildnumber": 22, 08:24:50 INFO - "slavename": "t-yosemite-r7-0384", 08:24:50 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:24:50 INFO - "platform": "yosemite_r7", 08:24:50 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:24:50 INFO - "branch": "mozilla-aurora", 08:24:50 INFO - "repo_path": "releases/mozilla-aurora", 08:24:50 INFO - "revision": "43763ed575bf103382d58e175e0ed8b90a83e874", 08:24:50 INFO - "stage_platform": "macosx64", 08:24:50 INFO - "builduid": "f70257de4fa54735ae58007535d91014", 08:24:50 INFO - "slavebuilddir": "test" 08:24:50 INFO - } 08:24:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:24:50 INFO - Running post-action listener: _resource_record_post_action 08:24:50 INFO - [mozharness: 2016-12-26 16:24:50.313358Z] Finished read-buildbot-config step (success) 08:24:50 INFO - [mozharness: 2016-12-26 16:24:50.313479Z] Running download-and-extract step. 08:24:50 INFO - Running pre-action listener: _resource_record_pre_action 08:24:50 INFO - Running main action method: download_and_extract 08:24:50 INFO - mkdir: /builds/slave/test/build/tests 08:24:50 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')]} 08:24:50 INFO - trying https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:24:50 INFO - Downloading https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/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 08:24:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/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 08:24:51 INFO - Downloaded 1199 bytes. 08:24:51 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:24:51 INFO - Using the following test package requirements: 08:24:51 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:24:51 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:24:51 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:24:51 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:24:51 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:24:51 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:24:51 INFO - u'jsshell-mac.zip'], 08:24:51 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:24:51 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:24:51 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:24:51 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:24:51 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:24:51 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:24:51 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:24:51 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:24:51 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:24:51 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:24:51 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:24:51 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:24:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:24:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:24:51 INFO - Fetch https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:24:51 INFO - Http code: 200 08:24:51 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 08:24:51 INFO - x-amz-cf-id: Vy22zZxQo47DPCa700YNtzOn7dS4DAaEEJNPaerMRQrNMJzWDUyUHg== 08:24:51 INFO - x-amz-version-id: 4UK6ogOpxJDUzJo8u60.mUFlGPTxbHjf 08:24:52 INFO - Expected file size: 16271078 08:24:52 INFO - Obtained file size: 16271078 08:24:58 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 08:24:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:24:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:24:58 INFO - Fetch https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:24:59 INFO - Http code: 200 08:24:59 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 08:24:59 INFO - x-amz-cf-id: 9Ptek453EY496rtU0qwOTRgjk77Onr-QavuXYkcVq8bRnvTRj5ZfpA== 08:24:59 INFO - x-amz-version-id: 4UK6ogOpxJDUzJo8u60.mUFlGPTxbHjf 08:24:59 INFO - Expected file size: 16271078 08:24:59 INFO - Obtained file size: 16271078 08:25:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:25:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:25:05 INFO - Fetch https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:25:08 INFO - Http code: 200 08:25:08 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 08:25:08 INFO - x-amz-cf-id: D9S0vT1g682qAfMaC52XEmAe7-xvOfjIJCB0oOMwXjCxnzsFIouF5A== 08:25:08 INFO - x-amz-version-id: 7YyEyjROpKstmmWHT0ffSnC1iYc.FJHn 08:25:08 INFO - Expected file size: 14409038 08:25:08 INFO - Obtained file size: 14409038 08:25:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:09 INFO - trying https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:25:09 INFO - Downloading https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:25:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/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 08:25:11 INFO - Downloaded 109823207 bytes. 08:25:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:25:11 INFO - mkdir: /builds/slave/test/properties 08:25:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:25:11 INFO - Writing to file /builds/slave/test/properties/build_url 08:25:11 INFO - Contents: 08:25:11 INFO - build_url:https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:25:12 INFO - Running post-action listener: _resource_record_post_action 08:25:12 INFO - Running post-action listener: set_extra_try_arguments 08:25:12 INFO - [mozharness: 2016-12-26 16:25:12.379642Z] Finished download-and-extract step (success) 08:25:12 INFO - [mozharness: 2016-12-26 16:25:12.379757Z] Running populate-webroot step. 08:25:12 INFO - Running pre-action listener: _resource_record_pre_action 08:25:12 INFO - Running main action method: populate_webroot 08:25:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:25:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:25:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:25:12 INFO - '--tppagecycles', 08:25:12 INFO - '7'], 08:25:12 INFO - 'tests': ['tsvgm']}}, 08:25:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:25:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:25:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:25:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:25:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:25:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:25:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:12 INFO - 'talos_options': ['--disable-e10s'], 08:25:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:25:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:25:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:12 INFO - 'talos_options': ['--disable-e10s'], 08:25:12 INFO - 'tests': ['damp', 'tps']}, 08:25:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:12 INFO - 'tests': ['damp', 'tps']}, 08:25:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:25:12 INFO - 'tests': ['dromaeo_dom']}, 08:25:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:25:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:25:12 INFO - 'tests': ['basic_compositor_video']}, 08:25:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:25:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:25:12 INFO - 'tests': ['a11yr', 08:25:12 INFO - 'ts_paint', 08:25:12 INFO - 'tpaint', 08:25:12 INFO - 'sessionrestore', 08:25:12 INFO - 'sessionrestore_no_auto_restore', 08:25:12 INFO - 'tabpaint']}, 08:25:12 INFO - 'other-e10s': {'tests': ['a11yr', 08:25:12 INFO - 'ts_paint', 08:25:12 INFO - 'tpaint', 08:25:12 INFO - 'sessionrestore', 08:25:12 INFO - 'sessionrestore_no_auto_restore', 08:25:12 INFO - 'tabpaint']}, 08:25:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:25:12 INFO - 'ts_paint', 08:25:12 INFO - 'tpaint', 08:25:12 INFO - 'sessionrestore', 08:25:12 INFO - 'sessionrestore_no_auto_restore', 08:25:12 INFO - 'tabpaint']}, 08:25:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:25:12 INFO - 'ts_paint', 08:25:12 INFO - 'tpaint', 08:25:12 INFO - 'sessionrestore', 08:25:12 INFO - 'sessionrestore_no_auto_restore', 08:25:12 INFO - 'tabpaint']}, 08:25:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:25:12 INFO - 'tests': ['a11yr', 08:25:12 INFO - 'ts_paint', 08:25:12 INFO - 'tpaint', 08:25:12 INFO - 'sessionrestore', 08:25:12 INFO - 'sessionrestore_no_auto_restore', 08:25:12 INFO - 'tabpaint']}, 08:25:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:25:12 INFO - 'tests': ['a11yr', 08:25:12 INFO - 'ts_paint', 08:25:12 INFO - 'tpaint', 08:25:12 INFO - 'sessionrestore', 08:25:12 INFO - 'sessionrestore_no_auto_restore', 08:25:12 INFO - 'tabpaint']}, 08:25:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:25:12 INFO - 'tests': ['tsvgx', 08:25:12 INFO - 'tsvgr_opacity', 08:25:12 INFO - 'tart', 08:25:12 INFO - 'tscrollx', 08:25:12 INFO - 'cart']}, 08:25:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:25:12 INFO - 'tsvgr_opacity', 08:25:12 INFO - 'tart', 08:25:12 INFO - 'tscrollx', 08:25:12 INFO - 'cart']}, 08:25:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:12 INFO - 'talos_options': ['--disable-e10s'], 08:25:12 INFO - 'tests': ['tp5o']}, 08:25:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:12 INFO - 'tests': ['tp5o']}, 08:25:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:12 INFO - 'talos_options': ['--disable-e10s', 08:25:12 INFO - '--xperf_path', 08:25:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:25:12 INFO - 'tests': ['tp5n']}, 08:25:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:12 INFO - 'talos_options': ['--xperf_path', 08:25:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:25:12 INFO - 'tests': ['tp5n']}}, 08:25:12 INFO - 'talos.zip': {'path': '', 08:25:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:25:12 INFO - Running post-action listener: _resource_record_post_action 08:25:12 INFO - [mozharness: 2016-12-26 16:25:12.398492Z] Finished populate-webroot step (success) 08:25:12 INFO - [mozharness: 2016-12-26 16:25:12.398617Z] Running create-virtualenv step. 08:25:12 INFO - Running pre-action listener: _resource_record_pre_action 08:25:12 INFO - Running main action method: create_virtualenv 08:25:12 INFO - Creating virtualenv /builds/slave/test/build/venv 08:25:12 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 08:25:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:25:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:25:12 INFO - Using real prefix '/tools/python27' 08:25:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:25:13 INFO - Installing distribute.............................................................................................................................................................................................done. 08:25:16 INFO - Installing pip.................done. 08:25:16 INFO - Return code: 0 08:25:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:25:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:25:16 INFO - Reading from file tmpfile_stdout 08:25:16 INFO - Output received: 08:25:16 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) 08:25:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:25:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107cf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04147b9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b07d30>, '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 0x10796ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107affb20>, '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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 08:25:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:25:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:25:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DA3Lf5pwgi/Render', 08:25:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:16 INFO - 'HOME': '/Users/cltbld', 08:25:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:16 INFO - 'LOGNAME': 'cltbld', 08:25:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:16 INFO - 'MOZ_NO_REMOTE': '1', 08:25:16 INFO - 'NO_EM_RESTART': '1', 08:25:16 INFO - 'PAGER': '/bin/cat', 08:25:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:16 INFO - 'PWD': '/builds/slave/test', 08:25:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:16 INFO - 'SHELL': '/bin/bash', 08:25:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GchF0pPrvf/Listeners', 08:25:16 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 08:25:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:16 INFO - 'USER': 'cltbld', 08:25:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:16 INFO - 'XPC_FLAGS': '0x0', 08:25:16 INFO - 'XPC_SERVICE_NAME': '0', 08:25:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:16 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 08:25:16 INFO - Cleaning up... 08:25:16 INFO - Return code: 0 08:25:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:25:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107cf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04147b9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b07d30>, '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 0x10796ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107affb20>, '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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 08:25:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:25:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:25:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DA3Lf5pwgi/Render', 08:25:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:16 INFO - 'HOME': '/Users/cltbld', 08:25:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:16 INFO - 'LOGNAME': 'cltbld', 08:25:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:16 INFO - 'MOZ_NO_REMOTE': '1', 08:25:16 INFO - 'NO_EM_RESTART': '1', 08:25:16 INFO - 'PAGER': '/bin/cat', 08:25:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:16 INFO - 'PWD': '/builds/slave/test', 08:25:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:16 INFO - 'SHELL': '/bin/bash', 08:25:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GchF0pPrvf/Listeners', 08:25:16 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 08:25:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:16 INFO - 'USER': 'cltbld', 08:25:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:16 INFO - 'XPC_FLAGS': '0x0', 08:25:16 INFO - 'XPC_SERVICE_NAME': '0', 08:25:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:16 INFO - Downloading/unpacking psutil>=3.1.1 08:25:16 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 08:25:16 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 08:25:16 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 08:25:16 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 08:25:16 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 08:25:16 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 08:25:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:25:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:25:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:25:20 INFO - Installing collected packages: psutil 08:25:20 INFO - Running setup.py install for psutil 08:25:21 INFO - building 'psutil._psutil_osx' extension 08:25:21 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 08:25:21 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 08:25:21 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 08:25:21 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 08:25:21 INFO - building 'psutil._psutil_posix' extension 08:25:21 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 08:25:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:25:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:25:21 INFO - ^ 08:25:21 INFO - 1 warning generated. 08:25:21 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 08:25:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:25:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:25:22 INFO - Successfully installed psutil 08:25:22 INFO - Cleaning up... 08:25:22 INFO - Return code: 0 08:25:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:22 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 0x107cf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04147b9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b07d30>, '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 0x10796ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107affb20>, '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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 08:25:22 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 08:25:22 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 08:25:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DA3Lf5pwgi/Render', 08:25:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:22 INFO - 'HOME': '/Users/cltbld', 08:25:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:22 INFO - 'LOGNAME': 'cltbld', 08:25:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:22 INFO - 'MOZ_NO_REMOTE': '1', 08:25:22 INFO - 'NO_EM_RESTART': '1', 08:25:22 INFO - 'PAGER': '/bin/cat', 08:25:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:22 INFO - 'PWD': '/builds/slave/test', 08:25:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:22 INFO - 'SHELL': '/bin/bash', 08:25:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GchF0pPrvf/Listeners', 08:25:22 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 08:25:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:22 INFO - 'USER': 'cltbld', 08:25:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:22 INFO - 'XPC_FLAGS': '0x0', 08:25:22 INFO - 'XPC_SERVICE_NAME': '0', 08:25:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:25:22 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 08:25:22 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 08:25:22 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 08:25:22 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 08:25:22 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 08:25:22 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 08:25:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:25:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:25:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:25:25 INFO - Installing collected packages: mozsystemmonitor 08:25:25 INFO - Running setup.py install for mozsystemmonitor 08:25:25 INFO - Successfully installed mozsystemmonitor 08:25:25 INFO - Cleaning up... 08:25:25 INFO - Return code: 0 08:25:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:25:25 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')]} 08:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:25 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')]} 08:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:25 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 0x107cf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04147b9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b07d30>, '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 0x10796ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107affb20>, '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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 08:25:25 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 08:25:25 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 08:25:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DA3Lf5pwgi/Render', 08:25:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:25 INFO - 'HOME': '/Users/cltbld', 08:25:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:25 INFO - 'LOGNAME': 'cltbld', 08:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:25 INFO - 'MOZ_NO_REMOTE': '1', 08:25:25 INFO - 'NO_EM_RESTART': '1', 08:25:25 INFO - 'PAGER': '/bin/cat', 08:25:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:25 INFO - 'PWD': '/builds/slave/test', 08:25:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:25 INFO - 'SHELL': '/bin/bash', 08:25:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GchF0pPrvf/Listeners', 08:25:25 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 08:25:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:25 INFO - 'USER': 'cltbld', 08:25:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:25 INFO - 'XPC_FLAGS': '0x0', 08:25:25 INFO - 'XPC_SERVICE_NAME': '0', 08:25:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:25 INFO - Downloading/unpacking jsonschema==2.5.1 08:25:25 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 08:25:25 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 08:25:25 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 08:25:25 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 08:25:25 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 08:25:25 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 08:25:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:25:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:25:28 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 08:25:28 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 08:25:28 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 08:25:28 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 08:25:28 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 08:25:28 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 08:25:28 INFO - Downloading functools32-3.2.3-2.tar.gz 08:25:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:25:28 INFO - warning: no files found matching '*.txt' 08:25:28 INFO - no previously-included directories found matching 'build' 08:25:28 INFO - no previously-included directories found matching 'dist' 08:25:28 INFO - no previously-included directories found matching '.git*' 08:25:28 INFO - Installing collected packages: jsonschema, functools32 08:25:28 INFO - Running setup.py install for functools32 08:25:28 INFO - warning: no files found matching '*.txt' 08:25:28 INFO - no previously-included directories found matching 'build' 08:25:28 INFO - no previously-included directories found matching 'dist' 08:25:28 INFO - no previously-included directories found matching '.git*' 08:25:28 INFO - Successfully installed jsonschema functools32 08:25:28 INFO - Cleaning up... 08:25:28 INFO - Return code: 0 08:25:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:25: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')]} 08:25:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25: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')]} 08:25:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25: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', '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 0x107cf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04147b9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b07d30>, '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 0x10796ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107affb20>, '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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 08:25: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:25: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 functools32==3.2.3-2 08:25:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DA3Lf5pwgi/Render', 08:25:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:28 INFO - 'HOME': '/Users/cltbld', 08:25:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:28 INFO - 'LOGNAME': 'cltbld', 08:25:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:28 INFO - 'MOZ_NO_REMOTE': '1', 08:25:28 INFO - 'NO_EM_RESTART': '1', 08:25:28 INFO - 'PAGER': '/bin/cat', 08:25:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:28 INFO - 'PWD': '/builds/slave/test', 08:25:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:28 INFO - 'SHELL': '/bin/bash', 08:25:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GchF0pPrvf/Listeners', 08:25:28 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 08:25:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:28 INFO - 'USER': 'cltbld', 08:25:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:28 INFO - 'XPC_FLAGS': '0x0', 08:25:28 INFO - 'XPC_SERVICE_NAME': '0', 08:25:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:25:29 INFO - Cleaning up... 08:25:29 INFO - Return code: 0 08:25:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107cf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04147b9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b07d30>, '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 0x10796ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107affb20>, '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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 08:25:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:25:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:25:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DA3Lf5pwgi/Render', 08:25:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:29 INFO - 'HOME': '/Users/cltbld', 08:25:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:29 INFO - 'LOGNAME': 'cltbld', 08:25:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:29 INFO - 'MOZ_NO_REMOTE': '1', 08:25:29 INFO - 'NO_EM_RESTART': '1', 08:25:29 INFO - 'PAGER': '/bin/cat', 08:25:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:29 INFO - 'PWD': '/builds/slave/test', 08:25:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:29 INFO - 'SHELL': '/bin/bash', 08:25:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GchF0pPrvf/Listeners', 08:25:29 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 08:25:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:29 INFO - 'USER': 'cltbld', 08:25:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:29 INFO - 'XPC_FLAGS': '0x0', 08:25:29 INFO - 'XPC_SERVICE_NAME': '0', 08:25:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:29 INFO - Downloading/unpacking blobuploader==1.2.4 08:25: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 08:25: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 08:25: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 08:25: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 08:25: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 08:25: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 08:25:31 INFO - Downloading blobuploader-1.2.4.tar.gz 08:25:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:25:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:25: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 08:25: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 08:25: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 08:25: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 08:25: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 08:25: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 08:25:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:25:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:25: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 08:25: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 08:25: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 08:25: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 08:25: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 08:25: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 08:25:33 INFO - Downloading docopt-0.6.1.tar.gz 08:25:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:25:33 INFO - Installing collected packages: blobuploader, requests, docopt 08:25:33 INFO - Running setup.py install for blobuploader 08:25:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:25:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:25:33 INFO - Running setup.py install for requests 08:25:33 INFO - Running setup.py install for docopt 08:25:34 INFO - Successfully installed blobuploader requests docopt 08:25:34 INFO - Cleaning up... 08:25:34 INFO - Return code: 0 08:25:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:34 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 0x107cf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04147b9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b07d30>, '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 0x10796ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107affb20>, '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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 08:25:34 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 08:25:34 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 08:25:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DA3Lf5pwgi/Render', 08:25:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:34 INFO - 'HOME': '/Users/cltbld', 08:25:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:34 INFO - 'LOGNAME': 'cltbld', 08:25:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:34 INFO - 'MOZ_NO_REMOTE': '1', 08:25:34 INFO - 'NO_EM_RESTART': '1', 08:25:34 INFO - 'PAGER': '/bin/cat', 08:25:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:34 INFO - 'PWD': '/builds/slave/test', 08:25:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:34 INFO - 'SHELL': '/bin/bash', 08:25:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GchF0pPrvf/Listeners', 08:25:34 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 08:25:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:34 INFO - 'USER': 'cltbld', 08:25:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:34 INFO - 'XPC_FLAGS': '0x0', 08:25:34 INFO - 'XPC_SERVICE_NAME': '0', 08:25:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:25:34 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-nnFsyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:25:34 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-XSH8U2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:25:34 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-AysD7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:25:34 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-PN7UiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:25:34 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-2TKdYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:25:35 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-G0p4kB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:25:35 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-XH4Ehs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:25:35 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-ls61vM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:25:35 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-0TNc9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:25:35 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-HmWCnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:25:35 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-_JN5tO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:25:35 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-BVEr00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:25:35 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-_offO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:25:35 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-AO7rbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:35 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-8MY6pD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:25:36 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-pJSDxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:25:36 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-B3YQ6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:25:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:25:36 INFO - Running setup.py install for manifestparser 08:25:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:25:36 INFO - Running setup.py install for mozcrash 08:25:36 INFO - Running setup.py install for mozdebug 08:25:36 INFO - Running setup.py install for mozdevice 08:25:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:25:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:25:36 INFO - Running setup.py install for mozfile 08:25:37 INFO - Running setup.py install for mozhttpd 08:25:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:25:37 INFO - Running setup.py install for mozinfo 08:25:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:25:37 INFO - Running setup.py install for mozInstall 08:25:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:25:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:25:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:25:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:25:37 INFO - Running setup.py install for mozleak 08:25:37 INFO - Running setup.py install for mozlog 08:25:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:25:37 INFO - Running setup.py install for moznetwork 08:25:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:25:37 INFO - Running setup.py install for mozprocess 08:25:38 INFO - Running setup.py install for mozprofile 08:25:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Running setup.py install for mozrunner 08:25:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Running setup.py install for mozscreenshot 08:25:38 INFO - Running setup.py install for moztest 08:25:38 INFO - Running setup.py install for mozversion 08:25:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:25:38 INFO - Cleaning up... 08:25:38 INFO - Return code: 0 08:25:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:38 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 0x107cf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04147b9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b07d30>, '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 0x10796ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107affb20>, '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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 08:25:38 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 08:25:38 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 08:25:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DA3Lf5pwgi/Render', 08:25:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:38 INFO - 'HOME': '/Users/cltbld', 08:25:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:38 INFO - 'LOGNAME': 'cltbld', 08:25:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:38 INFO - 'MOZ_NO_REMOTE': '1', 08:25:38 INFO - 'NO_EM_RESTART': '1', 08:25:38 INFO - 'PAGER': '/bin/cat', 08:25:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:38 INFO - 'PWD': '/builds/slave/test', 08:25:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:38 INFO - 'SHELL': '/bin/bash', 08:25:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GchF0pPrvf/Listeners', 08:25:38 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 08:25:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:38 INFO - 'USER': 'cltbld', 08:25:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:38 INFO - 'XPC_FLAGS': '0x0', 08:25:38 INFO - 'XPC_SERVICE_NAME': '0', 08:25:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:25:39 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-NjQb0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:25:39 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)) 08:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:25:39 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-Zi1jFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:25:39 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)) 08:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:25:39 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-KSsSDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:25:39 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)) 08:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:25:39 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-aFcvrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:25:39 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)) 08:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:25:39 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-zXgNiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:25:39 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)) 08:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:25:39 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-NkvmC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:25:39 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)) 08:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:25:39 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-PIRVxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:25:39 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)) 08:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:25:39 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-umgcBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:25:39 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)) 08:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:25:39 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-STWFzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:25:40 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-5E5OBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:25:40 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-4uRf7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:25:40 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-FrRA83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:25:40 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-_CIUh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:25:40 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-w62O8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:40 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-oiS4Ez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:25:40 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-CrUrnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:25:40 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-ru0W4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:25:40 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)) 08:25:40 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)) 08:25:40 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)) 08:25:40 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)) 08:25:40 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)) 08:25:40 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)) 08:25:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:25:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:43 INFO - Downloading blessings-1.6.tar.gz 08:25:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:25:43 INFO - Installing collected packages: blessings 08:25:43 INFO - Running setup.py install for blessings 08:25:43 INFO - Successfully installed blessings 08:25:43 INFO - Cleaning up... 08:25:43 INFO - Return code: 0 08:25:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:25:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:25:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:25:44 INFO - Reading from file tmpfile_stdout 08:25:44 INFO - Current package versions: 08:25:44 INFO - blessings == 1.6 08:25:44 INFO - blobuploader == 1.2.4 08:25:44 INFO - docopt == 0.6.1 08:25:44 INFO - functools32 == 3.2.3-2 08:25:44 INFO - jsonschema == 2.5.1 08:25:44 INFO - manifestparser == 1.1 08:25:44 INFO - mozInstall == 1.12 08:25:44 INFO - mozcrash == 1.0 08:25:44 INFO - mozdebug == 0.1 08:25:44 INFO - mozdevice == 0.48 08:25:44 INFO - mozfile == 1.2 08:25:44 INFO - mozhttpd == 0.7 08:25:44 INFO - mozinfo == 0.9 08:25:44 INFO - mozleak == 0.1 08:25:44 INFO - mozlog == 3.4 08:25:44 INFO - moznetwork == 0.27 08:25:44 INFO - mozprocess == 0.23 08:25:44 INFO - mozprofile == 0.28 08:25:44 INFO - mozrunner == 6.13 08:25:44 INFO - mozscreenshot == 0.1 08:25:44 INFO - mozsystemmonitor == 0.3 08:25:44 INFO - moztest == 0.7 08:25:44 INFO - mozversion == 1.4 08:25:44 INFO - psutil == 3.1.1 08:25:44 INFO - requests == 1.2.3 08:25:44 INFO - wsgiref == 0.1.2 08:25:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25:44 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')]} 08:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:44 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')]} 08:25:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:44 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 0x107cf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04147b9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b07d30>, '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 0x10796ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107affb20>, '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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 08:25:44 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 08:25:44 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 08:25:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DA3Lf5pwgi/Render', 08:25:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:44 INFO - 'HOME': '/Users/cltbld', 08:25:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:44 INFO - 'LOGNAME': 'cltbld', 08:25:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:44 INFO - 'MOZ_NO_REMOTE': '1', 08:25:44 INFO - 'NO_EM_RESTART': '1', 08:25:44 INFO - 'PAGER': '/bin/cat', 08:25:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:44 INFO - 'PWD': '/builds/slave/test', 08:25:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:44 INFO - 'SHELL': '/bin/bash', 08:25:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GchF0pPrvf/Listeners', 08:25:44 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 08:25:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:44 INFO - 'USER': 'cltbld', 08:25:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:44 INFO - 'XPC_FLAGS': '0x0', 08:25:44 INFO - 'XPC_SERVICE_NAME': '0', 08:25:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:44 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)) 08:25:44 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)) 08:25:44 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)) 08:25:44 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)) 08:25:44 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)) 08:25:44 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)) 08:25:44 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)) 08:25:44 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)) 08:25:44 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)) 08:25:44 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)) 08:25:44 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)) 08:25:44 INFO - Cleaning up... 08:25:44 INFO - Return code: 0 08:25:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:25:44 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')]} 08:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:44 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')]} 08:25:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:44 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 0x107cf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd04147b9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b07d30>, '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 0x10796ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107affb20>, '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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 08:25:44 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 08:25:44 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 08:25:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DA3Lf5pwgi/Render', 08:25:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:44 INFO - 'HOME': '/Users/cltbld', 08:25:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:44 INFO - 'LOGNAME': 'cltbld', 08:25:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:44 INFO - 'MOZ_NO_REMOTE': '1', 08:25:44 INFO - 'NO_EM_RESTART': '1', 08:25:44 INFO - 'PAGER': '/bin/cat', 08:25:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:44 INFO - 'PWD': '/builds/slave/test', 08:25:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:44 INFO - 'SHELL': '/bin/bash', 08:25:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GchF0pPrvf/Listeners', 08:25:44 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 08:25:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:44 INFO - 'USER': 'cltbld', 08:25:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:44 INFO - 'XPC_FLAGS': '0x0', 08:25:44 INFO - 'XPC_SERVICE_NAME': '0', 08:25:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:25:44 INFO - Cleaning up... 08:25:44 INFO - Return code: 0 08:25:44 INFO - Running post-action listener: _resource_record_post_action 08:25:44 INFO - Running post-action listener: _start_resource_monitoring 08:25:44 INFO - Starting resource monitoring. 08:25:44 INFO - [mozharness: 2016-12-26 16:25:44.705752Z] Finished create-virtualenv step (success) 08:25:44 INFO - [mozharness: 2016-12-26 16:25:44.706703Z] Running install step. 08:25:44 INFO - Running pre-action listener: _resource_record_pre_action 08:25:44 INFO - Running main action method: install 08:25:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:25:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:25:44 INFO - Reading from file tmpfile_stdout 08:25:44 INFO - Detecting whether we're running mozinstall >=1.0... 08:25:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:25:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:25:45 INFO - Reading from file tmpfile_stdout 08:25:45 INFO - Output received: 08:25:45 INFO - Usage: mozinstall [options] installer 08:25:45 INFO - Options: 08:25:45 INFO - -h, --help show this help message and exit 08:25:45 INFO - -d DEST, --destination=DEST 08:25:45 INFO - Directory to install application into. [default: 08:25:45 INFO - "/builds/slave/test"] 08:25:45 INFO - --app=APP Application being installed. [default: firefox] 08:25:45 INFO - mkdir: /builds/slave/test/build/application 08:25:45 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'] 08:25:45 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 08:26:16 INFO - Reading from file tmpfile_stdout 08:26:16 INFO - Output received: 08:26:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:26:16 INFO - Running post-action listener: _resource_record_post_action 08:26:16 INFO - [mozharness: 2016-12-26 16:26:16.663184Z] Finished install step (success) 08:26:16 INFO - [mozharness: 2016-12-26 16:26:16.663326Z] Running run-tests step. 08:26:16 INFO - Running pre-action listener: _resource_record_pre_action 08:26:16 INFO - Running main action method: run_tests 08:26:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:26:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:26:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:26:16 INFO - 2016-12-26 08:26:16.678 screenresolution[1640:8409] starting screenresolution argv=screenresolution get 08:26:16 INFO - 2016-12-26 08:26:16.680 screenresolution[1640:8409] Display 0: 1600x1200x32@60 08:26:16 INFO - 2016-12-26 08:26:16.686 screenresolution[1641:8411] starting screenresolution argv=screenresolution list 08:26:16 INFO - Available Modes on Display 0 08:26:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:26:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:26:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:26:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:26:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:26:16 INFO - Intel Iris: 08:26:16 INFO - Chipset Model: Intel Iris 08:26:16 INFO - Type: GPU 08:26:16 INFO - Bus: Built-In 08:26:16 INFO - VRAM (Dynamic, Max): 1536 MB 08:26:16 INFO - Vendor: Intel (0x8086) 08:26:16 INFO - Device ID: 0x0a2e 08:26:16 INFO - Revision ID: 0x0009 08:26:16 INFO - Displays: 08:26:16 INFO - Display: 08:26:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:26:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:26:16 INFO - Display Serial Number: 1600x1200 60 08:26:16 INFO - Main Display: Yes 08:26:16 INFO - Mirror: Off 08:26:16 INFO - Online: Yes 08:26:16 INFO - Rotation: Supported 08:26:16 INFO - Return code: 0 08:26:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:26:16 INFO - Python 2.7.3 08:26:16 INFO - Return code: 0 08:26:16 INFO - grabbing minidump binary from tooltool 08:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:16 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 0x107b07d30>, '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 0x10796ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107affb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:26:16 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 08:26:16 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 08:26:16 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 08:26:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:26:17 INFO - Return code: 0 08:26:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:26:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:26:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:26:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:26:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:26:17 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/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0384', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:26:17 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/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0384 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:26:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DA3Lf5pwgi/Render', 08:26:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:17 INFO - 'HOME': '/Users/cltbld', 08:26:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:17 INFO - 'LOGNAME': 'cltbld', 08:26:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:26:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:17 INFO - 'MOZ_NO_REMOTE': '1', 08:26:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:26:17 INFO - 'NO_EM_RESTART': '1', 08:26:17 INFO - 'PAGER': '/bin/cat', 08:26:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:17 INFO - 'PWD': '/builds/slave/test', 08:26:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:26:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:17 INFO - 'SHELL': '/bin/bash', 08:26:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GchF0pPrvf/Listeners', 08:26:17 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 08:26:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:17 INFO - 'USER': 'cltbld', 08:26:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:17 INFO - 'XPC_FLAGS': '0x0', 08:26:17 INFO - 'XPC_SERVICE_NAME': '0', 08:26:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:17 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/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0384', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:26:17 INFO - mozversion application_buildid: 20161226064937 08:26:17 INFO - mozversion application_changeset: 43763ed575bf103382d58e175e0ed8b90a83e874 08:26:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:26:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:26:17 INFO - mozversion application_name: Firefox 08:26:17 INFO - mozversion application_remotingname: firefox-dev 08:26:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:26:17 INFO - mozversion application_vendor: Mozilla 08:26:17 INFO - mozversion application_version: 52.0a2 08:26:17 INFO - mozversion platform_buildid: 20161226064937 08:26:17 INFO - mozversion platform_changeset: 43763ed575bf103382d58e175e0ed8b90a83e874 08:26:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:26:17 INFO - mozversion platform_version: 52.0a2 08:26:17 INFO - using testdate: 1482769577 08:26:17 INFO - actual date: 1482769577 08:26:17 INFO - starting webserver on 'localhost:49243' 08:26:17 INFO - SUITE-START | Running 2 tests 08:26:17 INFO - TEST-START | dromaeo_css 08:26:17 INFO - operating with platform_type : mac_ 08:26:17 INFO - Initialising browser for dromaeo_css test... 08:26:17 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/tmpeCi8gi/profile http://localhost:49243/getInfo.html) 08:26:20 INFO - PROCESS | 1651 | 2016-12-26 08:26:20.263 plugin-container[1653:8589] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e47, name = 'com.apple.tsm.portname' 08:26:20 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:26:20 INFO - PROCESS | 1651 | 2016-12-26 08:26:20.266 plugin-container[1653:8589] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 08:26:20 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:26:20 INFO - PROCESS | 1651 | 2016-12-26 08:26:20.266 plugin-container[1653:8589] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:26:20 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 08:26:20 INFO - PROCESS | 1651 | colorDepth:24 08:26:20 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 08:26:20 INFO - PROCESS | 1651 | __metrics 08:26:20 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:26:21 INFO - TEST-INFO | 1651: exit 0 08:26:21 INFO - Browser initialized. 08:26:21 INFO - Running cycle 1/1 for dromaeo_css test... 08:26:21 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/tmpeCi8gi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:26:23 INFO - PROCESS | 1655 | 2016-12-26 08:26:23.707 plugin-container[1656:8727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 08:26:23 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:26:23 INFO - PROCESS | 1655 | 2016-12-26 08:26:23.708 plugin-container[1656:8727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 08:26:23 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:26:23 INFO - PROCESS | 1655 | 2016-12-26 08:26:23.708 plugin-container[1656:8727] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:26:33 INFO - PROCESS | 1655 | RSS: Main: 229769216 08:26:33 INFO - PROCESS | 1655 | 08:27:23 INFO - PROCESS | 1655 | 1482769643058 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] 08:29:37 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 08:29:39 INFO - PROCESS | 1655 | RSS: Main: 234082304 08:29:39 INFO - PROCESS | 1655 | 08:32:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 08:32:41 INFO - PROCESS | 1655 | RSS: Main: 243597312 08:32:41 INFO - PROCESS | 1655 | 08:35:43 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 08:35:44 INFO - PROCESS | 1655 | RSS: Main: 246521856 08:35:44 INFO - PROCESS | 1655 | 08:38:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 08:38:47 INFO - PROCESS | 1655 | RSS: Main: 245739520 08:38:47 INFO - PROCESS | 1655 | 08:41:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 08:41:50 INFO - PROCESS | 1655 | RSS: Main: 249122816 08:41:50 INFO - PROCESS | 1655 | 08:44:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 08:44:53 INFO - PROCESS | 1655 | RSS: Main: 250589184 08:44:53 INFO - PROCESS | 1655 | 08:44:53 INFO - PROCESS | 1655 | __start_tp_report 08:44:53 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 08:44:53 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 08:44:53 INFO - PROCESS | 1655 | |i|pagename|runs| 08:44:53 INFO - PROCESS | 1655 | |0;dojo.html;3396.779209351392;3402.2515046689737;3440.1915549845767;3447.3622379859726;3464.1166502541846;5729.627574207673;5752.02215623344;5820.825375238739;5834.183214349994;5854.765809367631;5793.449622285887;5852.502537284326;5858.472737453629;5892.764289428428;5897.351291357947;5268.262443257938;5317.8936421271555;5339.359276886759;5357.383900851393;5361.249425080492;5345.812896548621;5370;5374.7043912584795;5384.542313903323;5450.236966824647;5179.300794392765;5236.162214045753;5351.411344752074;5355.357357117132;5375.569954403638;5261.1056120459425;5276.92084618731;5291.2327712481665;5312.096943519611;5313.946860531384;5110.897782044338;5227.0068686949735;5237.083510385667;5252.947470525322;5300.628955973083;5823.417658234185;5826.388229235911;5857;5867.823965281048;5897.675627840467;6065.150878876917;6189.195404597419;6197.194364732601;6206.348333425016;6234.532410069263;6261.87476250477;6285.842853928642;6287.748490060392;6291.307956124822;6301.590396624205;5890.793822216245;5926.377730338339;5947.940520594782;5950.642961422285;5959.761609535613;6167.074938759222;6241.687915604201;6318.880238893001;6341.431720153345;6377.553571249968;6136.4578081083455;6148.323684394713;6171.290301615282;6174.9073763893575;6228.252609686776;4903.901921961541;6211.9068213976825;6246.781362652286;6291.55959082869;6305.117283580303;5190.429052804188;5317.707526086102;6323.557350985387;6337;6338.683065846689;6101.389861013863;6113.052476866093;6147.754089836446;6158.846028849314;6321.525885558602;4983.8006479741125;5035.924655317508;5038.445770965191;5042.067217564762;5051.848444546669;3780.527434070741;4444.022315090675;4470.8435204767675;4482.641388688897;4495.505494395614;4571.337156112345;4916.582090522345;5867.6772777497645;5880.970595147082;5924.348321684612;6235.719392627343;6266.905996410058;6284.340144284784;6294.874102517924;6327.430531252117;5870.853228669232;5912.881742365214;5927.496162826122;5929.881402372014;5948.048312270016;5336;5339.519443250126;5399.001184780828;5425.321834770653;5434.565234781208;6260.186175797117;6266.68666566679;6285.96857015712;6327.050942358591;6392.968035159981;6155.261368635793;6263.592866463665;6264.874702505833;6279.811605651837;6287.245530536365;6252.499800015897;6256.374362563707;6268.655223962726;6358.364163583605;6425.807225783234;6063.696815159313;6280.152179455715;6295.3704629538515;6318.462930650762;6326.841828954313;6118.418750218722;6230.06549017651;6258.217722784652;6276.121343012073;6279.995200767855;6185.505159587312;6248.656323902229;6256.749730010931;6265.96867015662;6303.212098487689;6241.906371404341;6242.250929888442;6251.843703907439;6264.5614806964895;6291.842703932255;5722.8569285766525;5909.409059094056;5940.79207227745;5959.165716799738;6034.879302414015;5132.5124113209185;6137.294211165595;6179.443850053517;6199.411055949678;6212.937870621236;5903.734331955158;6031.215941927521;6073.757049717962;6074;6095.878082438415 08:44:53 INFO - PROCESS | 1655 | |1;ext.html;479.3624479442342;504.81826542444713;511.5370589616396;535.9682610973874;549.0090386851731;28835.27911802206;38111.80944095279;39293;39317.21365572687;41008;39797.806065818;40276.19447611046;40286;40293.39559906604;40326;9130.178283954443;9340.626374944994;9365.81268374632;9380.202682771938;9382.624695012191;9347.906520934806;9447.480388578626;9451.102145296185;9458.668946586871;9461.858072128889;9403.85894211584;9408.341416100875;9464.858027129598;9465.384749991228;9470.431774093542;9370.85943710845;9419.481928493964;9429.29280303973;9464.905350946472;9474.194693451065;9273.953630231874;9361.672341468016;9379.812403751886;9386.530673466299;9390.48352340621;37908.24183516342;38429;38435;38535.42196867049;38657;3238.481842905124;3271.8527666255077;3332.783369081002;3402.387570237356;3448.1207292140343;29213;29488.820447182086;29564;29886.701132988826;29969.400611987858;3165.493521036623;3193.5050067239613;3234.886778962725;3240.044186964851;3243.383757086146;505187;505780;508492.45753771364;510905.9999999963;511836.44081779727;59124.52188695231;59448;59542.10686839701;59620.40379596235;59785;51444;60749.69625151846;61367.6931615348;61408.38591614116;61535.692321539;15714.607134821721;15835.366585336687;15926.840731592536;15943.44197953066;15956.2819673115;12189.329586872807;12243;12255.019598432104;12267.815982760265;12270.570530031406;14182.503612373517;17829.7325540117;18367.632647347247;18389.90805045966;18445.262189512538;6171.660558669317;7053.576785392893;7124.893126603105;7580.620968951531;7667.271609197117;832.6710949175163;853.6201390381345;936.1855185988234;1001.9098281154731;1025.1747343388645;28964.855175723987;29396;29555.704442955295;29611.703882960894;29885.850570747007;1000.8448690452991;1030.1810061001522;1108.6064447121316;1121.5962253588702;1132.5583022621183;15295.61760955985;15369.385224591115;15370.846291537166;15429.368280577733;15584.532464026097;660497;666102;667768;668051;669880;55081.724591376784;55326.17010744842;55392.446075538726;55480.44519554753;55543.16685249725;1208.1663652080038;1323.3515577367214;1354.552997510819;1365.1877133105786;1367.3505085084505;28991.855040724662;29136;29314.56028159622;29892.103236902927;30125;47575;47658.28512572245;47950.760246198544;48257.51742482671;48459;587581.1241887526;589288;590828;593721;595425;20519.4870128242;20936.267230647463;24350;25013.249602511954;25784.226473205836;35821.641783582876;44789.552104479844;44934.55065449304;44958.325625116304;45476.772616138034;36559.26881462303;36892.077698057765;37180;37232.441513376776;37644;28932.710672893;36812;37861.24277514489;38061.619383806916;38288.46846126124;31069.53395699021;34641.8429788427;34642.65357346394;36129;36626.633733662326;31908.840455797574;33272.83363583167;33804.492932605535;33855.661443386234;33903.66096339005;25274;25300.987960481376;25416.618750718386;25487.87256063708;25804.483910321313 08:44:53 INFO - PROCESS | 1655 | |2;jquery.html;369.4034134872181;374.89877733012077;380.5490493764889;384.3235904807538;395.63206218217067;18446.509721187635;19515.804841951576;19639.410817675474;19951;20036.59926801463;19472.41582752516;19701.901490492564;19969;19969.60060798783;20293.49266268344;5729.598928075046;5761.193432919395;5966;5971.552133589977;6034.788782392618;3838.961610383902;6033.879322413549;6058.697065146747;6063.605865618742;6065.514758819284;4018.075842556214;5743.483086522212;6020.819375418744;6067.908981365283;6095.634261944298;5851.561132915027;6035.758569657209;6091.543134264925;6100.115483254903;6100.633961962297;5005.774740136702;5906.616069955439;5910;5911.201987731644;5917.467427931464;11307.886921130766;13050.673733156747;23309.300720978397;23493.295201143992;24352.634710479357;1972.6054789042248;1978.4658142301641;2247.2359397804826;2418.6896942355024;2438.207582535683;9568.76078098053;9865.704028879145;9935.701928942144;10172;10305;1779.9911000445045;1929.411529483507;1967.931122410723;1992.7907569705117;2240.7423146338215;133734;140989.99999999898;144406;150409;150926;20858.28842250528;22086.558268834855;22134.55730885373;22327.218547350763;22475.325740227818;35226;35306.117347066014;35774;35915.820420897726;36034.91895243147;10350.327228730259;10608.893911060944;10770.299930504492;10785.946070269756;10868.61959831402;8770.210681038738;8786.736397908073;8790.164934331227;8931.642734290555;8988.730338089868;9515.809683806285;9713.805723885624;9795.461249631338;9803.89216318495;9840.65557705477;1595.0668858717547;3611.165235290337;3777.7355585109326;3889.9416508752392;3906.511686039245;779.3648176735942;837.6858677995751;853.8600967708179;900.4642237868458;997.8652881861001;19528.8047119527;20599.38201853947;20675.793242067688;20683.689744653842;20871.58256834855;914.5518695838991;925.6760133953197;930.5347326336831;938.7090002099371;1022.948852557384;10294;10587.57649694019;10661.84007239892;10686.73283167927;10782.083522900484;556406;594170;622417;623529;625204;124631.99999999818;125795.3710231443;128134;131435.68564314424;134269;946.5267366316841;951.5527701980059;961.1733908838394;962.6871266838306;962.7544976031068;14766.409343626136;16679.74980375271;17359.045252511234;17479.563010924827;17872.91063544674;51933.22100168425;52186.47813521816;52251.47748522466;52830.735846320524;53092.73453632707;70111.29888701251;74273.6286318565;74699;74823.25176748163;75565.62217188878;6733.293004234485;6851.725930962905;6915.930840691529;6986.196587392515;7113.53762005468;11141.498632561392;11233.494492747683;11261.549538018715;11528.308301501937;11780.528778849093;9086.954565227132;9334.813303734023;9609;9704.614068507131;10022.047905448972;7023.508354415347;9639;9642.228621710387;9840.753981150528;9843.114119729258;7088.751893683696;7170.498065135599;7216.061911951413;7279.162896266997;7477.327040566375;8973.28213742912;10622.8406573903;11022.779544408906;11125.554977801121;11401;7126.608036557832;7168.641567921687;7260.4554658401885;7479.551226926402;10258.025487578669 08:44:53 INFO - PROCESS | 1655 | |3;mootools.html;1373.972520549589;1459.511063793629;1626.178779716243;1764.8235176482344;1786.3390545498169;7000.844860598008;7028.875379939211;8105.91894081059;8587.184217499344;8590.570471476432;7992.440529162961;8293.04629967554;8372.120927302636;8382.329413646909;8412.45319054261;2306;2348.976510234893;2352.0356653771955;2355.9293221203393;2361.4686695493547;1816.5821860971919;2340.6489026646054;2369.7748713872243;2392.5334559760854;2464.4578192797558;2326.0928237987114;2331.73185083715;2350.788429041386;2353.49399879026;2359.4101474631343;2312.3756585721844;2315.0276883708716;2329.4758679297192;2340.964885526727;2346.5072334809624;2334.871582062986;2361.409647588103;2367.633016882386;2370.988145059281;2378.4410663494236;8305.086440491541;8349.081601023883;8400.873986890201;8423.831523369498;8499.532525711084;5112.514311140436;5264.184051472028;5269.235960785664;5285.2600635911;5286.471352864722;3222.613286405623;3406.3017081498233;3498.1254686328416;3570.285942811448;3574.982125089358;926.3978414030867;1005.7938122684832;1036.124474818777;1083.4257843342962;1094.2121672395863;10269.89730102697;10355.326903751233;10363.585456581728;10401.947990260076;10452.216083793746;4505.346724724896;4578.519255478127;4653.278741795028;4660.557247061523;4748.548887855648;9525.14273715369;9590.66432674867;9593.232541396672;9619.422834629944;9637.325387222967;5237.371515418175;5252.422233554306;5252.606054545924;5255.605829562722;5261.447548007404;4279.764612946318;4301.204277208726;4721.976390118028;4758.1673207188605;4788.640851936118;3845.69234461248;3881.2819628368843;3935.193285376491;3963.3460479020637;3999.460072890181;1254.9623511294676;1281.1928485054357;1290.9548165814151;1291.3607764156802;1295.0999055656227;689.5104475822222;754.2080815144077;879.9604017819214;909.9090090990848;917.7246825952187;5484.341878974548;5580.497755202051;5638.661680299113;5709.457601527848;5758.596898217084;886.5389997201419;928.7863791328033;936.3726303376754;943.2689665509286;952.9618815247312;3246.513023046562;3788.4995902704636;3805.505284313077;3844.115853353744;3848.249591329664;9736.513174341255;10221.846672299922;10222.080012798884;10232.846507302396;10244.385336879812;10173.643922462677;10181.592736290613;10183.134433573081;10200.897991020143;10220.386776793417;6155.784547540815;7753.418493613001;7805.882911756442;7877.094134174528;7921.841563168704;5687.289088863892;5693.089105743061;5824.621399609012;5826.067829147316;5884.235049443545;9239.491827949527;9307.488088155216;9311.161995420445;9366.297527685314;9428.340016199074;6853.725850965906;8791.5164665944;9463.053694630757;9590.568424421057;9604.615815367308;6469.061986012096;6730.764423245163;6812.386885180357;7042.260562640849;7169.533980291264;7696.653650585625;7897;7901.407394445555;7903.446758727064;7905.169957154416;5755.913661295001;5784.132380143013;6825.2833452486775;7007.334303241184;7073.434125270069;5816.8947899898885;6655.367740064686;6899.17209934811;6943.479239057192;6947.340002699735;5446.809361672323;6425;6469.547131700924;6518.380753828379;6570.244421891352;4693.131770622309;4738.170820106398;4785.401824771903;4868.829590964299;4962.354893863775;4829.589484893823;4866.124097662455;4897.657163998629;4899.216125419915;4939.283803848493 08:44:53 INFO - PROCESS | 1655 | |4;prototype.html;291.0801866103114;307.35148835956113;307.53408585992213;317.2876891378855;318.53493898907595;5081.517255860692;5348.46515348465;5502.889942201158;5519;5553.833384998451;4754.9524504754945;5041.017001684664;5583.246261754662;5588.329400471949;5597.2498426054535;3609.783412995229;4188.120494696117;4226.788660566967;4245.448091748069;4265.1469706058915;3808.352580061396;3896.103896103898;4198.454200953872;4256.127493863766;4258.233517966764;3315.519249708791;3676.9448458273146;4165.500139983191;4175.248455278049;4195.538490766014;4155.522114956773;4179.426590265325;4188.958110418887;4191.7694526801015;4209.116085622023;3332.333533293351;3469.4795780632853;3612.356377848073;3622.2755448910325;3650.8539658413633;4897.559219670246;4908.993656300449;5305.628605997582;5373.372973243085;5464.060258608177;4067.8983025424304;4578.656600754956;4781.163296423112;4801.863170888956;4807.399075115611;4868.683535570177;5850.67050382718;5876.7355469004;5889.8822023559715;5934.8813023739285;3647.8326935380687;3719.0516418313427;3907.648311651965;3934;3978.5424676162033;4914.975425122887;5784.334801497839;5841.941580584183;5927.762889484416;6202.751889924398;3637.3452778499595;3856.2287542491613;3882.689384849262;4064.451299074587;4086.2849001425225;3746.763568022546;3976.1053762903116;4088.3049881520215;4094.9385759213637;4127.711060225755;4253.680973926967;4751.3348131261655;4789.664723469394;4807.014562014779;4832.540908613676;5891.911621325684;5941.851453713692;5945.910811337833;5984.311804142492;6003.669798161056;1098.8352346512722;1262.6275248801626;1317.617890811654;1318.1168617026615;1322.4511827591634;798.6086817459403;864.8789169516273;883.4566741453959;896.6326352312645;897.5512243878061;4129.97935010323;5145.202493613496;5234.424213336606;5270.552003079704;5274.97362513185;4843.588294994895;5335.039692855324;5737.971310143423;5753.33836608787;5791.681457519877;4296.2266791977745;4381.343401426912;4507;5065.797368105308;5243.187305967581;2823.054276817248;2983.4331477019296;2990.1627544287635;3005.969940300613;3044.375902939892;5397.3523177218985;5478.58910581708;6054.152418661391;6057.213122128983;6081.5742897996715;5049.469805670426;5773.824396581947;5804.6807425592;5847.44449277318;5911.876743418823;5229.849433124783;5295.576353891756;5537.957291177463;5611.607187496836;6068.787592434332;4939.48135445773;5237.895242095061;5285.418603953485;5565.6104072714525;5689;4902.3872015998;5291.523762861361;5642.971785141048;5684.261046063985;5752.884942301047;5326.946730532645;5794.768209271582;6009;6082.482988945989;6092.725827337869;3459.4118999770603;3659.323025240339;3764.6235376462137;3845.442410850467;3850.7304488686646;5041.521055499722;5148.562372198405;5473.452654734654;5752.856178595569;5836.883262334814;4995.8517837328845;5771.7114144293455;5946.226990491208;5958.106284057426;6020.367861374668;5929.02171141774;5965.761369545169;5978.521718262616;5979.940200597938;6019.367966363468;5213.791448342024;5450.974709036523;5609.859753506032;5824.912626310694;5858.677772722541;5119.539241468286;5383.7308134593895;5418.773356781717;5979.312379076462;5986.8026394720355;5452.809151679672;5822.359540450462;5829.417058294137;5845.736941837713;5848.9179501792305 08:44:53 INFO - PROCESS | 1655 | |5;yui.html;590.4886665872732;630.4798541203509;650.7201903181633;743.0785825576287;762.9771106866792;10986;14568.490102846403;14762.704745905075;14788.926055369735;14793.630159246024;11059.944700276488;13294.202347859135;13368.331583420839;13417.262050587215;13881.93059034706;578.4158000419585;787.6652422720349;815.8857759913617;818.6848063495538;822.1244374740882;793.4247670438914;808.9797755056142;810.7891948093509;811.8944537210184;813.093400858042;831.4553967151526;843.7215718812777;856.0968178571604;856.8500512410335;858.0818524179131;832.3133414932644;844.3245403677065;854.747849384433;857.1171693156059;860.3633311349619;630.8170630517144;636.0491065856575;638.141699414287;638.7604648256904;639.6545865232769;12094.09294302922;14255.28723563828;14265.787408070326;14340.856591434056;14341.56975290743;11169.553217871275;13087.47650093995;13153.342332883318;13283.535076272285;13362.398692058881;10968.725781855437;11042.33745975244;11090.669119705606;11190.608328708457;11571.42142892852;3507.228409749851;3550.218951830593;3601.477785721082;3674.401635288658;3730.8694195703292;88520.5573972126;89723.65414518722;89922.10077899203;90263;92806.07193928042;10038.849417258747;10239.436830974219;10242.815927289783;10293.279470436997;10336.586536538454;16059.759103613454;18487.53781155482;19777.307794227134;19797.109130089182;20002.299919502748;7571.962140189264;7602.1257555380735;7735.690572377154;7828.373730101578;7837.608119593998;4204.558521355275;4231.661467082626;4247.787610619456;4261.21167583998;4349.151915376535;3400.6939375456327;3486.3550243205086;3516.9824150879595;3542.6634469725336;3543.6102028776813;639.114825966031;646.1341801985362;647.7020570537605;651.8956966885276;667.9732810687518;514.2209552527924;552.4392741367515;558.9301337332834;584.4360192414256;614.0543562913064;7951.324137448266;9022.375972325044;9027.909720902708;9120.954395228115;9188.035256298126;571.4713889652138;572.842468321215;600.9663378988133;622.8723111762168;632.718440294074;4282.828686852491;4455.532169122261;4483.2826747720865;4498.460184777848;4580.496145424001;73547;74351.25648743444;106902;107631.46184269029;120654;55447.44552554504;77729.61135194288;81381;98573.50713246388;99623.00376996282;62892.68553657294;83746;84358;84659.5767021161;85958;9065.002849686398;9472.05897001699;9780.065980205767;9820.067093626094;9846.901530984598;50794.74602626963;50930.453477325245;51069;51625.48374516357;52300;73691.6315418441;75715;75939;76731;78923;12130;12228;12311.938440307742;12440.688982775502;12592.181508201938;13861.584152475441;13911.582652520441;14013.43946242139;14166.929165354108;14167.504137355143;12785.232886026868;12792.04059695508;12864.228146310877;12873.356332183166;12920.612381628565;12762.872371276168;12981.480740770263;13040.804387933998;13077;13224;12094.334811585448;12477.5008999639;12499.93750031244;12931.418086185957;13215.60353189406;10569.788604228026;10589.682309530726;11123.777524449628;11514.654560363202;11578.71053223676;11005.394703291397;11201.439928003405;11328.716782080184;11457;11659.650210493699 08:44:53 INFO - PROCESS | 1655 | __end_tp_report 08:44:53 INFO - PROCESS | 1655 | __start_cc_report 08:44:53 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,371 08:44:53 INFO - PROCESS | 1655 | __end_cc_report 08:44:53 INFO - PROCESS | 1655 | __startTimestamp1482770693554__endTimestamp 08:44:53 INFO - PROCESS | 1655 | 08:44:53 INFO - PROCESS | 1655 | ------- Summary: start ------- 08:44:53 INFO - PROCESS | 1655 | Number of tests: 6 08:44:53 INFO - PROCESS | 1655 | 08:44:53 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5774.81 Median:5959.17 stddev:652.25 (10.9%) stddev-sans-first:627.00 08:44:53 INFO - PROCESS | 1655 | Values: 3396.8 3402.3 3440.2 3447.4 3464.1 5729.6 5752.0 5820.8 5834.2 5854.8 5793.4 5852.5 5858.5 5892.8 5897.4 5268.3 5317.9 5339.4 5357.4 5361.2 5345.8 5370.0 5374.7 5384.5 5450.2 5179.3 5236.2 5351.4 5355.4 5375.6 5261.1 5276.9 5291.2 5312.1 5313.9 5110.9 5227.0 5237.1 5252.9 5300.6 5823.4 5826.4 5857.0 5867.8 5897.7 6065.2 6189.2 6197.2 6206.3 6234.5 6261.9 6285.8 6287.7 6291.3 6301.6 5890.8 5926.4 5947.9 5950.6 5959.8 6167.1 6241.7 6318.9 6341.4 6377.6 6136.5 6148.3 6171.3 6174.9 6228.3 4903.9 6211.9 6246.8 6291.6 6305.1 5190.4 5317.7 6323.6 6337.0 6338.7 6101.4 6113.1 6147.8 6158.8 6321.5 4983.8 5035.9 5038.4 5042.1 5051.8 3780.5 4444.0 4470.8 4482.6 4495.5 4571.3 4916.6 5867.7 5881.0 5924.3 6235.7 6266.9 6284.3 6294.9 6327.4 5870.9 5912.9 5927.5 5929.9 5948.0 5336.0 5339.5 5399.0 5425.3 5434.6 6260.2 6266.7 6286.0 6327.1 6393.0 6155.3 6263.6 6264.9 6279.8 6287.2 6252.5 6256.4 6268.7 6358.4 6425.8 6063.7 6280.2 6295.4 6318.5 6326.8 6118.4 6230.1 6258.2 6276.1 6280.0 6185.5 6248.7 6256.7 6266.0 6303.2 6241.9 6242.3 6251.8 6264.6 6291.8 5722.9 5909.4 5940.8 5959.2 6034.9 5132.5 6137.3 6179.4 6199.4 6212.9 5903.7 6031.2 6073.8 6074.0 6095.9 08:44:53 INFO - PROCESS | 1655 | 08:44:53 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:70873.30 Median:38358.73 stddev:158615.91 (413.5%) stddev-sans-first:158972.82 08:44:53 INFO - PROCESS | 1655 | Values: 479.4 504.8 511.5 536.0 549.0 28835.3 38111.8 39293.0 39317.2 41008.0 39797.8 40276.2 40286.0 40293.4 40326.0 9130.2 9340.6 9365.8 9380.2 9382.6 9347.9 9447.5 9451.1 9458.7 9461.9 9403.9 9408.3 9464.9 9465.4 9470.4 9370.9 9419.5 9429.3 9464.9 9474.2 9274.0 9361.7 9379.8 9386.5 9390.5 37908.2 38429.0 38435.0 38535.4 38657.0 3238.5 3271.9 3332.8 3402.4 3448.1 29213.0 29488.8 29564.0 29886.7 29969.4 3165.5 3193.5 3234.9 3240.0 3243.4 505187.0 505780.0 508492.5 510906.0 511836.4 59124.5 59448.0 59542.1 59620.4 59785.0 51444.0 60749.7 61367.7 61408.4 61535.7 15714.6 15835.4 15926.8 15943.4 15956.3 12189.3 12243.0 12255.0 12267.8 12270.6 14182.5 17829.7 18367.6 18389.9 18445.3 6171.7 7053.6 7124.9 7580.6 7667.3 832.7 853.6 936.2 1001.9 1025.2 28964.9 29396.0 29555.7 29611.7 29885.9 1000.8 1030.2 1108.6 1121.6 1132.6 15295.6 15369.4 15370.8 15429.4 15584.5 660497.0 666102.0 667768.0 668051.0 669880.0 55081.7 55326.2 55392.4 55480.4 55543.2 1208.2 1323.4 1354.6 1365.2 1367.4 28991.9 29136.0 29314.6 29892.1 30125.0 47575.0 47658.3 47950.8 48257.5 48459.0 587581.1 589288.0 590828.0 593721.0 595425.0 20519.5 20936.3 24350.0 25013.2 25784.2 35821.6 44789.6 44934.6 44958.3 45476.8 36559.3 36892.1 37180.0 37232.4 37644.0 28932.7 36812.0 37861.2 38061.6 38288.5 31069.5 34641.8 34642.7 36129.0 36626.6 31908.8 33272.8 33804.5 33855.7 33903.7 25274.0 25301.0 25416.6 25487.9 25804.5 08:44:53 INFO - PROCESS | 1655 | 08:44:53 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:36368.73 Median:4511.93 stddev:101506.24 (2249.7%) stddev-sans-first:101755.00 08:44:53 INFO - PROCESS | 1655 | Values: 369.4 374.9 380.5 384.3 395.6 18446.5 19515.8 19639.4 19951.0 20036.6 19472.4 19701.9 19969.0 19969.6 20293.5 5729.6 5761.2 5966.0 5971.6 6034.8 3839.0 6033.9 6058.7 6063.6 6065.5 4018.1 5743.5 6020.8 6067.9 6095.6 5851.6 6035.8 6091.5 6100.1 6100.6 5005.8 5906.6 5910.0 5911.2 5917.5 11307.9 13050.7 23309.3 23493.3 24352.6 1972.6 1978.5 2247.2 2418.7 2438.2 9568.8 9865.7 9935.7 10172.0 10305.0 1780.0 1929.4 1967.9 1992.8 2240.7 133734.0 140990.0 144406.0 150409.0 150926.0 20858.3 22086.6 22134.6 22327.2 22475.3 35226.0 35306.1 35774.0 35915.8 36034.9 10350.3 10608.9 10770.3 10785.9 10868.6 8770.2 8786.7 8790.2 8931.6 8988.7 9515.8 9713.8 9795.5 9803.9 9840.7 1595.1 3611.2 3777.7 3889.9 3906.5 779.4 837.7 853.9 900.5 997.9 19528.8 20599.4 20675.8 20683.7 20871.6 914.6 925.7 930.5 938.7 1022.9 10294.0 10587.6 10661.8 10686.7 10782.1 556406.0 594170.0 622417.0 623529.0 625204.0 124632.0 125795.4 128134.0 131435.7 134269.0 946.5 951.6 961.2 962.7 962.8 14766.4 16679.7 17359.0 17479.6 17872.9 51933.2 52186.5 52251.5 52830.7 53092.7 70111.3 74273.6 74699.0 74823.3 75565.6 6733.3 6851.7 6915.9 6986.2 7113.5 11141.5 11233.5 11261.5 11528.3 11780.5 9087.0 9334.8 9609.0 9704.6 10022.0 7023.5 9639.0 9642.2 9840.8 9843.1 7088.8 7170.5 7216.1 7279.2 7477.3 8973.3 10622.8 11022.8 11125.6 11401.0 7126.6 7168.6 7260.5 7479.6 10258.0 08:44:53 INFO - PROCESS | 1655 | 08:44:53 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5385.74 Median:5252.51 stddev:2928.95 (55.8%) stddev-sans-first:2921.65 08:44:53 INFO - PROCESS | 1655 | Values: 1374.0 1459.5 1626.2 1764.8 1786.3 7000.8 7028.9 8105.9 8587.2 8590.6 7992.4 8293.0 8372.1 8382.3 8412.5 2306.0 2349.0 2352.0 2355.9 2361.5 1816.6 2340.6 2369.8 2392.5 2464.5 2326.1 2331.7 2350.8 2353.5 2359.4 2312.4 2315.0 2329.5 2341.0 2346.5 2334.9 2361.4 2367.6 2371.0 2378.4 8305.1 8349.1 8400.9 8423.8 8499.5 5112.5 5264.2 5269.2 5285.3 5286.5 3222.6 3406.3 3498.1 3570.3 3575.0 926.4 1005.8 1036.1 1083.4 1094.2 10269.9 10355.3 10363.6 10401.9 10452.2 4505.3 4578.5 4653.3 4660.6 4748.5 9525.1 9590.7 9593.2 9619.4 9637.3 5237.4 5252.4 5252.6 5255.6 5261.4 4279.8 4301.2 4722.0 4758.2 4788.6 3845.7 3881.3 3935.2 3963.3 3999.5 1255.0 1281.2 1291.0 1291.4 1295.1 689.5 754.2 880.0 909.9 917.7 5484.3 5580.5 5638.7 5709.5 5758.6 886.5 928.8 936.4 943.3 953.0 3246.5 3788.5 3805.5 3844.1 3848.2 9736.5 10221.8 10222.1 10232.8 10244.4 10173.6 10181.6 10183.1 10200.9 10220.4 6155.8 7753.4 7805.9 7877.1 7921.8 5687.3 5693.1 5824.6 5826.1 5884.2 9239.5 9307.5 9311.2 9366.3 9428.3 6853.7 8791.5 9463.1 9590.6 9604.6 6469.1 6730.8 6812.4 7042.3 7169.5 7696.7 7897.0 7901.4 7903.4 7905.2 5755.9 5784.1 6825.3 7007.3 7073.4 5816.9 6655.4 6899.2 6943.5 6947.3 5446.8 6425.0 6469.5 6518.4 6570.2 4693.1 4738.2 4785.4 4868.8 4962.4 4829.6 4866.1 4897.7 4899.2 4939.3 08:44:53 INFO - PROCESS | 1655 | 08:44:53 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4618.30 Median:5181.18 stddev:1432.67 (27.7%) stddev-sans-first:1399.39 08:44:53 INFO - PROCESS | 1655 | Values: 291.1 307.4 307.5 317.3 318.5 5081.5 5348.5 5502.9 5519.0 5553.8 4755.0 5041.0 5583.2 5588.3 5597.2 3609.8 4188.1 4226.8 4245.4 4265.1 3808.4 3896.1 4198.5 4256.1 4258.2 3315.5 3676.9 4165.5 4175.2 4195.5 4155.5 4179.4 4189.0 4191.8 4209.1 3332.3 3469.5 3612.4 3622.3 3650.9 4897.6 4909.0 5305.6 5373.4 5464.1 4067.9 4578.7 4781.2 4801.9 4807.4 4868.7 5850.7 5876.7 5889.9 5934.9 3647.8 3719.1 3907.6 3934.0 3978.5 4915.0 5784.3 5841.9 5927.8 6202.8 3637.3 3856.2 3882.7 4064.5 4086.3 3746.8 3976.1 4088.3 4094.9 4127.7 4253.7 4751.3 4789.7 4807.0 4832.5 5891.9 5941.9 5945.9 5984.3 6003.7 1098.8 1262.6 1317.6 1318.1 1322.5 798.6 864.9 883.5 896.6 897.6 4130.0 5145.2 5234.4 5270.6 5275.0 4843.6 5335.0 5738.0 5753.3 5791.7 4296.2 4381.3 4507.0 5065.8 5243.2 2823.1 2983.4 2990.2 3006.0 3044.4 5397.4 5478.6 6054.2 6057.2 6081.6 5049.5 5773.8 5804.7 5847.4 5911.9 5229.8 5295.6 5538.0 5611.6 6068.8 4939.5 5237.9 5285.4 5565.6 5689.0 4902.4 5291.5 5643.0 5684.3 5752.9 5326.9 5794.8 6009.0 6082.5 6092.7 3459.4 3659.3 3764.6 3845.4 3850.7 5041.5 5148.6 5473.5 5752.9 5836.9 4995.9 5771.7 5946.2 5958.1 6020.4 5929.0 5965.8 5978.5 5979.9 6019.4 5213.8 5451.0 5609.9 5824.9 5858.7 5119.5 5383.7 5418.8 5979.3 5986.8 5452.8 5822.4 5829.4 5845.7 5848.9 08:44:53 INFO - PROCESS | 1655 | 08:44:53 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:19634.62 Median:4490.87 stddev:28325.89 (630.7%) stddev-sans-first:28369.26 08:44:53 INFO - PROCESS | 1655 | Values: 590.5 630.5 650.7 743.1 763.0 10986.0 14568.5 14762.7 14788.9 14793.6 11059.9 13294.2 13368.3 13417.3 13881.9 578.4 787.7 815.9 818.7 822.1 793.4 809.0 810.8 811.9 813.1 831.5 843.7 856.1 856.9 858.1 832.3 844.3 854.7 857.1 860.4 630.8 636.0 638.1 638.8 639.7 12094.1 14255.3 14265.8 14340.9 14341.6 11169.6 13087.5 13153.3 13283.5 13362.4 10968.7 11042.3 11090.7 11190.6 11571.4 3507.2 3550.2 3601.5 3674.4 3730.9 88520.6 89723.7 89922.1 90263.0 92806.1 10038.8 10239.4 10242.8 10293.3 10336.6 16059.8 18487.5 19777.3 19797.1 20002.3 7572.0 7602.1 7735.7 7828.4 7837.6 4204.6 4231.7 4247.8 4261.2 4349.2 3400.7 3486.4 3517.0 3542.7 3543.6 639.1 646.1 647.7 651.9 668.0 514.2 552.4 558.9 584.4 614.1 7951.3 9022.4 9027.9 9121.0 9188.0 571.5 572.8 601.0 622.9 632.7 4282.8 4455.5 4483.3 4498.5 4580.5 73547.0 74351.3 106902.0 107631.5 120654.0 55447.4 77729.6 81381.0 98573.5 99623.0 62892.7 83746.0 84358.0 84659.6 85958.0 9065.0 9472.1 9780.1 9820.1 9846.9 50794.7 50930.5 51069.0 51625.5 52300.0 73691.6 75715.0 75939.0 76731.0 78923.0 12130.0 12228.0 12311.9 12440.7 12592.2 13861.6 13911.6 14013.4 14166.9 14167.5 12785.2 12792.0 12864.2 12873.4 12920.6 12762.9 12981.5 13040.8 13077.0 13224.0 12094.3 12477.5 12499.9 12931.4 13215.6 10569.8 10589.7 11123.8 11514.7 11578.7 11005.4 11201.4 11328.7 11457.0 11659.7 08:44:53 INFO - PROCESS | 1655 | -------- Summary: end -------- 08:44:53 INFO - PROCESS | 1655 | 08:44:54 INFO - TEST-INFO | 1655: exit 0 08:44:54 INFO - TEST-OK | dromaeo_css | took 1116918ms 08:44:54 INFO - TEST-START | kraken 08:44:54 INFO - operating with platform_type : mac_ 08:44:54 INFO - Initialising browser for kraken test... 08:44:54 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/tmpb7lHAM/profile http://localhost:49243/getInfo.html) 08:44:57 INFO - PROCESS | 1665 | 2016-12-26 08:44:57.111 plugin-container[1666:11082] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 08:44:57 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:57 INFO - PROCESS | 1665 | 2016-12-26 08:44:57.114 plugin-container[1666:11082] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d03, name = 'com.apple.CFPasteboardClient' 08:44:57 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:57 INFO - PROCESS | 1665 | 2016-12-26 08:44:57.114 plugin-container[1666:11082] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:44:57 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 08:44:57 INFO - PROCESS | 1665 | colorDepth:24 08:44:57 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 08:44:57 INFO - PROCESS | 1665 | __metrics 08:44:57 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:44:58 INFO - TEST-INFO | 1665: exit 0 08:44:58 INFO - Browser initialized. 08:44:58 INFO - Running cycle 1/1 for kraken test... 08:44:58 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/tmpb7lHAM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:45:00 INFO - PROCESS | 1667 | 2016-12-26 08:45:00.862 plugin-container[1668:11209] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 08:45:00 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:00 INFO - PROCESS | 1667 | 2016-12-26 08:45:00.863 plugin-container[1668:11209] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4507, name = 'com.apple.CFPasteboardClient' 08:45:00 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:00 INFO - PROCESS | 1667 | 2016-12-26 08:45:00.863 plugin-container[1668:11209] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:45:10 INFO - PROCESS | 1667 | RSS: Main: 229777408 08:45:10 INFO - PROCESS | 1667 | 08:45:13 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 08:45:14 INFO - PROCESS | 1667 | RSS: Main: 241840128 08:45:14 INFO - PROCESS | 1667 | 08:45:17 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 08:45:17 INFO - PROCESS | 1667 | RSS: Main: 249356288 08:45:17 INFO - PROCESS | 1667 | 08:45:21 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 08:45:21 INFO - PROCESS | 1667 | RSS: Main: 253181952 08:45:21 INFO - PROCESS | 1667 | 08:45:23 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 08:45:24 INFO - PROCESS | 1667 | RSS: Main: 253345792 08:45:24 INFO - PROCESS | 1667 | 08:45:26 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:45:26 INFO - PROCESS | 1667 | RSS: Main: 254652416 08:45:26 INFO - PROCESS | 1667 | 08:45:29 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 08:45:30 INFO - PROCESS | 1667 | RSS: Main: 258883584 08:45:30 INFO - PROCESS | 1667 | 08:45:33 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 08:45:34 INFO - PROCESS | 1667 | RSS: Main: 258195456 08:45:34 INFO - PROCESS | 1667 | 08:45:37 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 08:45:37 INFO - PROCESS | 1667 | RSS: Main: 258564096 08:45:37 INFO - PROCESS | 1667 | 08:45:39 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:45:40 INFO - PROCESS | 1667 | RSS: Main: 257249280 08:45:40 INFO - PROCESS | 1667 | 08:45:42 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:45:42 INFO - PROCESS | 1667 | RSS: Main: 257245184 08:45:42 INFO - PROCESS | 1667 | 08:45:44 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:45:45 INFO - PROCESS | 1667 | RSS: Main: 257286144 08:45:45 INFO - PROCESS | 1667 | 08:45:48 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:45:49 INFO - PROCESS | 1667 | RSS: Main: 257560576 08:45:49 INFO - PROCESS | 1667 | 08:45:52 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:45:53 INFO - PROCESS | 1667 | RSS: Main: 257417216 08:45:53 INFO - PROCESS | 1667 | 08:45:54 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 08:45:55 INFO - PROCESS | 1667 | RSS: Main: 259198976 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | __start_tp_report 08:45:55 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 08:45:55 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 08:45:55 INFO - PROCESS | 1667 | |i|pagename|runs| 08:45:55 INFO - PROCESS | 1667 | |0;ai-astar;107;117;117;119;116;117;141;120;115;119 08:45:55 INFO - PROCESS | 1667 | |1;audio-beat-detection;144;113;111;146;140;150;154;114;137;114 08:45:55 INFO - PROCESS | 1667 | |2;audio-dft;192;200;197;200;268;248;198;204;198;196 08:45:55 INFO - PROCESS | 1667 | |3;audio-fft;81;58;59;89;60;61;58;58;78;57 08:45:55 INFO - PROCESS | 1667 | |4;audio-oscillator;80;69;89;75;70;70;88;71;71;89 08:45:55 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;137;131;89;87;89;92;89;86;89;86 08:45:55 INFO - PROCESS | 1667 | |6;imaging-darkroom;98;159;126;172;139;131;170;107;115;150 08:45:55 INFO - PROCESS | 1667 | |7;imaging-desaturate;88;92;118;137;131;138;88;114;81;72 08:45:55 INFO - PROCESS | 1667 | |8;json-parse-financial;49;56;76;61;60;62;61;60;59;59 08:45:55 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;68;74;67;82;74;70;74;72;72;72 08:45:55 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;84;77;99;101;82;84;83;84;94;85 08:45:55 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;141;159;154;150;161;156;159;166;190;276 08:45:55 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;175;168;165;171;227;162;206;225;238;168 08:45:55 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;67;68;69;66;69;65;81;85;92;87 08:45:55 INFO - PROCESS | 1667 | __end_tp_report 08:45:55 INFO - PROCESS | 1667 | __start_cc_report 08:45:55 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,974 08:45:55 INFO - PROCESS | 1667 | __end_cc_report 08:45:55 INFO - PROCESS | 1667 | __startTimestamp1482770755227__endTimestamp 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | ------- Summary: start ------- 08:45:55 INFO - PROCESS | 1667 | Number of tests: 14 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:118.80 Median:118.00 stddev:8.60 (7.3%) stddev-sans-first:7.99 08:45:55 INFO - PROCESS | 1667 | Values: 107.0 117.0 117.0 119.0 116.0 117.0 141.0 120.0 115.0 119.0 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:132.30 Median:142.00 stddev:17.28 (12.2%) stddev-sans-first:17.80 08:45:55 INFO - PROCESS | 1667 | Values: 144.0 113.0 111.0 146.0 140.0 150.0 154.0 114.0 137.0 114.0 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:210.10 Median:200.00 stddev:25.86 (12.9%) stddev-sans-first:26.59 08:45:55 INFO - PROCESS | 1667 | Values: 192.0 200.0 197.0 200.0 268.0 248.0 198.0 204.0 198.0 196.0 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:65.90 Median:60.50 stddev:11.93 (19.7%) stddev-sans-first:11.33 08:45:55 INFO - PROCESS | 1667 | Values: 81.0 58.0 59.0 89.0 60.0 61.0 58.0 58.0 78.0 57.0 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:77.50 stddev:8.53 (11.0%) stddev-sans-first:8.99 08:45:55 INFO - PROCESS | 1667 | Values: 80.0 69.0 89.0 75.0 70.0 70.0 88.0 71.0 71.0 89.0 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:97.50 Median:89.00 stddev:19.37 (21.8%) stddev-sans-first:14.33 08:45:55 INFO - PROCESS | 1667 | Values: 137.0 131.0 89.0 87.0 89.0 92.0 89.0 86.0 89.0 86.0 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:136.70 Median:154.50 stddev:25.89 (16.8%) stddev-sans-first:23.37 08:45:55 INFO - PROCESS | 1667 | Values: 98.0 159.0 126.0 172.0 139.0 131.0 170.0 107.0 115.0 150.0 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:105.90 Median:76.50 stddev:24.59 (32.1%) stddev-sans-first:25.22 08:45:55 INFO - PROCESS | 1667 | Values: 88.0 92.0 118.0 137.0 131.0 138.0 88.0 114.0 81.0 72.0 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:60.30 Median:60.50 stddev:6.67 (11.0%) stddev-sans-first:5.68 08:45:55 INFO - PROCESS | 1667 | Values: 49.0 56.0 76.0 61.0 60.0 62.0 61.0 60.0 59.0 59.0 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:73.00 stddev:4.14 (5.7%) stddev-sans-first:4.06 08:45:55 INFO - PROCESS | 1667 | Values: 68.0 74.0 67.0 82.0 74.0 70.0 74.0 72.0 72.0 72.0 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:84.00 stddev:7.89 (9.4%) stddev-sans-first:8.28 08:45:55 INFO - PROCESS | 1667 | Values: 84.0 77.0 99.0 101.0 82.0 84.0 83.0 84.0 94.0 85.0 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:171.20 Median:160.00 stddev:38.95 (24.3%) stddev-sans-first:39.75 08:45:55 INFO - PROCESS | 1667 | Values: 141.0 159.0 154.0 150.0 161.0 156.0 159.0 166.0 190.0 276.0 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.50 Median:190.50 stddev:30.03 (15.8%) stddev-sans-first:31.32 08:45:55 INFO - PROCESS | 1667 | Values: 175.0 168.0 165.0 171.0 227.0 162.0 206.0 225.0 238.0 168.0 08:45:55 INFO - PROCESS | 1667 | 08:45:55 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.90 Median:75.00 stddev:10.19 (13.6%) stddev-sans-first:10.40 08:45:55 INFO - PROCESS | 1667 | Values: 67.0 68.0 69.0 66.0 69.0 65.0 81.0 85.0 92.0 87.0 08:45:55 INFO - PROCESS | 1667 | -------- Summary: end -------- 08:45:55 INFO - PROCESS | 1667 | 08:45:56 INFO - TEST-INFO | 1667: exit 0 08:45:56 INFO - TEST-OK | kraken | took 62118ms 08:45:56 INFO - SUITE-END | took 1179s 08:45:56 INFO - Completed test suite (00:19:39) 08:45:56 INFO - javascript benchmark 08:45:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7241.483297041307, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3396.779209351392, 3402.2515046689737, 3440.1915549845767, 3447.3622379859726, 3464.1166502541846, 5729.627574207673, 5752.02215623344, 5820.825375238739, 5834.183214349994, 5854.765809367631, 5793.449622285887, 5852.502537284326, 5858.472737453629, 5892.764289428428, 5897.351291357947, 5268.262443257938, 5317.8936421271555, 5339.359276886759, 5357.383900851393, 5361.249425080492, 5345.812896548621, 5370.0, 5374.7043912584795, 5384.542313903323, 5450.236966824647, 5179.300794392765, 5236.162214045753, 5351.411344752074, 5355.357357117132, 5375.569954403638, 5261.1056120459425, 5276.92084618731, 5291.2327712481665, 5312.096943519611, 5313.946860531384, 5110.897782044338, 5227.0068686949735, 5237.083510385667, 5252.947470525322, 5300.628955973083, 5823.417658234185, 5826.388229235911, 5857.0, 5867.823965281048, 5897.675627840467, 6065.150878876917, 6189.195404597419, 6197.194364732601, 6206.348333425016, 6234.532410069263, 6261.87476250477, 6285.842853928642, 6287.748490060392, 6291.307956124822, 6301.590396624205, 5890.793822216245, 5926.377730338339, 5947.940520594782, 5950.642961422285, 5959.761609535613, 6167.074938759222, 6241.687915604201, 6318.880238893001, 6341.431720153345, 6377.553571249968, 6136.4578081083455, 6148.323684394713, 6171.290301615282, 6174.9073763893575, 6228.252609686776, 4903.901921961541, 6211.9068213976825, 6246.781362652286, 6291.55959082869, 6305.117283580303, 5190.429052804188, 5317.707526086102, 6323.557350985387, 6337.0, 6338.683065846689, 6101.389861013863, 6113.052476866093, 6147.754089836446, 6158.846028849314, 6321.525885558602, 4983.8006479741125, 5035.924655317508, 5038.445770965191, 5042.067217564762, 5051.848444546669, 3780.527434070741, 4444.022315090675, 4470.8435204767675, 4482.641388688897, 4495.505494395614, 4571.337156112345, 4916.582090522345, 5867.6772777497645, 5880.970595147082, 5924.348321684612, 6235.719392627343, 6266.905996410058, 6284.340144284784, 6294.874102517924, 6327.430531252117, 5870.853228669232, 5912.881742365214, 5927.496162826122, 5929.881402372014, 5948.048312270016, 5336.0, 5339.519443250126, 5399.001184780828, 5425.321834770653, 5434.565234781208, 6260.186175797117, 6266.68666566679, 6285.96857015712, 6327.050942358591, 6392.968035159981, 6155.261368635793, 6263.592866463665, 6264.874702505833, 6279.811605651837, 6287.245530536365, 6252.499800015897, 6256.374362563707, 6268.655223962726, 6358.364163583605, 6425.807225783234, 6063.696815159313, 6280.152179455715, 6295.3704629538515, 6318.462930650762, 6326.841828954313, 6118.418750218722, 6230.06549017651, 6258.217722784652, 6276.121343012073, 6279.995200767855, 6185.505159587312, 6248.656323902229, 6256.749730010931, 6265.96867015662, 6303.212098487689, 6241.906371404341, 6242.250929888442, 6251.843703907439, 6264.5614806964895, 6291.842703932255, 5722.8569285766525, 5909.409059094056, 5940.79207227745, 5959.165716799738, 6034.879302414015, 5132.5124113209185, 6137.294211165595, 6179.443850053517, 6199.411055949678, 6212.937870621236, 5903.734331955158, 6031.215941927521, 6073.757049717962, 6074.0, 6095.878082438415], "value": 5734.877729888872, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.3624479442342, 504.81826542444713, 511.5370589616396, 535.9682610973874, 549.0090386851731, 28835.27911802206, 38111.80944095279, 39293.0, 39317.21365572687, 41008.0, 39797.806065818, 40276.19447611046, 40286.0, 40293.39559906604, 40326.0, 9130.178283954443, 9340.626374944994, 9365.81268374632, 9380.202682771938, 9382.624695012191, 9347.906520934806, 9447.480388578626, 9451.102145296185, 9458.668946586871, 9461.858072128889, 9403.85894211584, 9408.341416100875, 9464.858027129598, 9465.384749991228, 9470.431774093542, 9370.85943710845, 9419.481928493964, 9429.29280303973, 9464.905350946472, 9474.194693451065, 9273.953630231874, 9361.672341468016, 9379.812403751886, 9386.530673466299, 9390.48352340621, 37908.24183516342, 38429.0, 38435.0, 38535.42196867049, 38657.0, 3238.481842905124, 3271.8527666255077, 3332.783369081002, 3402.387570237356, 3448.1207292140343, 29213.0, 29488.820447182086, 29564.0, 29886.701132988826, 29969.400611987858, 3165.493521036623, 3193.5050067239613, 3234.886778962725, 3240.044186964851, 3243.383757086146, 505187.0, 505780.0, 508492.45753771364, 510905.9999999963, 511836.44081779727, 59124.52188695231, 59448.0, 59542.10686839701, 59620.40379596235, 59785.0, 51444.0, 60749.69625151846, 61367.6931615348, 61408.38591614116, 61535.692321539, 15714.607134821721, 15835.366585336687, 15926.840731592536, 15943.44197953066, 15956.2819673115, 12189.329586872807, 12243.0, 12255.019598432104, 12267.815982760265, 12270.570530031406, 14182.503612373517, 17829.7325540117, 18367.632647347247, 18389.90805045966, 18445.262189512538, 6171.660558669317, 7053.576785392893, 7124.893126603105, 7580.620968951531, 7667.271609197117, 832.6710949175163, 853.6201390381345, 936.1855185988234, 1001.9098281154731, 1025.1747343388645, 28964.855175723987, 29396.0, 29555.704442955295, 29611.703882960894, 29885.850570747007, 1000.8448690452991, 1030.1810061001522, 1108.6064447121316, 1121.5962253588702, 1132.5583022621183, 15295.61760955985, 15369.385224591115, 15370.846291537166, 15429.368280577733, 15584.532464026097, 660497.0, 666102.0, 667768.0, 668051.0, 669880.0, 55081.724591376784, 55326.17010744842, 55392.446075538726, 55480.44519554753, 55543.16685249725, 1208.1663652080038, 1323.3515577367214, 1354.552997510819, 1365.1877133105786, 1367.3505085084505, 28991.855040724662, 29136.0, 29314.56028159622, 29892.103236902927, 30125.0, 47575.0, 47658.28512572245, 47950.760246198544, 48257.51742482671, 48459.0, 587581.1241887526, 589288.0, 590828.0, 593721.0, 595425.0, 20519.4870128242, 20936.267230647463, 24350.0, 25013.249602511954, 25784.226473205836, 35821.641783582876, 44789.552104479844, 44934.55065449304, 44958.325625116304, 45476.772616138034, 36559.26881462303, 36892.077698057765, 37180.0, 37232.441513376776, 37644.0, 28932.710672893, 36812.0, 37861.24277514489, 38061.619383806916, 38288.46846126124, 31069.53395699021, 34641.8429788427, 34642.65357346394, 36129.0, 36626.633733662326, 31908.840455797574, 33272.83363583167, 33804.492932605535, 33855.661443386234, 33903.66096339005, 25274.0, 25300.987960481376, 25416.618750718386, 25487.87256063708, 25804.483910321313], "value": 19382.06383142581, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.4034134872181, 374.89877733012077, 380.5490493764889, 384.3235904807538, 395.63206218217067, 18446.509721187635, 19515.804841951576, 19639.410817675474, 19951.0, 20036.59926801463, 19472.41582752516, 19701.901490492564, 19969.0, 19969.60060798783, 20293.49266268344, 5729.598928075046, 5761.193432919395, 5966.0, 5971.552133589977, 6034.788782392618, 3838.961610383902, 6033.879322413549, 6058.697065146747, 6063.605865618742, 6065.514758819284, 4018.075842556214, 5743.483086522212, 6020.819375418744, 6067.908981365283, 6095.634261944298, 5851.561132915027, 6035.758569657209, 6091.543134264925, 6100.115483254903, 6100.633961962297, 5005.774740136702, 5906.616069955439, 5910.0, 5911.201987731644, 5917.467427931464, 11307.886921130766, 13050.673733156747, 23309.300720978397, 23493.295201143992, 24352.634710479357, 1972.6054789042248, 1978.4658142301641, 2247.2359397804826, 2418.6896942355024, 2438.207582535683, 9568.76078098053, 9865.704028879145, 9935.701928942144, 10172.0, 10305.0, 1779.9911000445045, 1929.411529483507, 1967.931122410723, 1992.7907569705117, 2240.7423146338215, 133734.0, 140989.99999999898, 144406.0, 150409.0, 150926.0, 20858.28842250528, 22086.558268834855, 22134.55730885373, 22327.218547350763, 22475.325740227818, 35226.0, 35306.117347066014, 35774.0, 35915.820420897726, 36034.91895243147, 10350.327228730259, 10608.893911060944, 10770.299930504492, 10785.946070269756, 10868.61959831402, 8770.210681038738, 8786.736397908073, 8790.164934331227, 8931.642734290555, 8988.730338089868, 9515.809683806285, 9713.805723885624, 9795.461249631338, 9803.89216318495, 9840.65557705477, 1595.0668858717547, 3611.165235290337, 3777.7355585109326, 3889.9416508752392, 3906.511686039245, 779.3648176735942, 837.6858677995751, 853.8600967708179, 900.4642237868458, 997.8652881861001, 19528.8047119527, 20599.38201853947, 20675.793242067688, 20683.689744653842, 20871.58256834855, 914.5518695838991, 925.6760133953197, 930.5347326336831, 938.7090002099371, 1022.948852557384, 10294.0, 10587.57649694019, 10661.84007239892, 10686.73283167927, 10782.083522900484, 556406.0, 594170.0, 622417.0, 623529.0, 625204.0, 124631.99999999818, 125795.3710231443, 128134.0, 131435.68564314424, 134269.0, 946.5267366316841, 951.5527701980059, 961.1733908838394, 962.6871266838306, 962.7544976031068, 14766.409343626136, 16679.74980375271, 17359.045252511234, 17479.563010924827, 17872.91063544674, 51933.22100168425, 52186.47813521816, 52251.47748522466, 52830.735846320524, 53092.73453632707, 70111.29888701251, 74273.6286318565, 74699.0, 74823.25176748163, 75565.62217188878, 6733.293004234485, 6851.725930962905, 6915.930840691529, 6986.196587392515, 7113.53762005468, 11141.498632561392, 11233.494492747683, 11261.549538018715, 11528.308301501937, 11780.528778849093, 9086.954565227132, 9334.813303734023, 9609.0, 9704.614068507131, 10022.047905448972, 7023.508354415347, 9639.0, 9642.228621710387, 9840.753981150528, 9843.114119729258, 7088.751893683696, 7170.498065135599, 7216.061911951413, 7279.162896266997, 7477.327040566375, 8973.28213742912, 10622.8406573903, 11022.779544408906, 11125.554977801121, 11401.0, 7126.608036557832, 7168.641567921687, 7260.4554658401885, 7479.551226926402, 10258.025487578669], "value": 10003.331523325085, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1373.972520549589, 1459.511063793629, 1626.178779716243, 1764.8235176482344, 1786.3390545498169, 7000.844860598008, 7028.875379939211, 8105.91894081059, 8587.184217499344, 8590.570471476432, 7992.440529162961, 8293.04629967554, 8372.120927302636, 8382.329413646909, 8412.45319054261, 2306.0, 2348.976510234893, 2352.0356653771955, 2355.9293221203393, 2361.4686695493547, 1816.5821860971919, 2340.6489026646054, 2369.7748713872243, 2392.5334559760854, 2464.4578192797558, 2326.0928237987114, 2331.73185083715, 2350.788429041386, 2353.49399879026, 2359.4101474631343, 2312.3756585721844, 2315.0276883708716, 2329.4758679297192, 2340.964885526727, 2346.5072334809624, 2334.871582062986, 2361.409647588103, 2367.633016882386, 2370.988145059281, 2378.4410663494236, 8305.086440491541, 8349.081601023883, 8400.873986890201, 8423.831523369498, 8499.532525711084, 5112.514311140436, 5264.184051472028, 5269.235960785664, 5285.2600635911, 5286.471352864722, 3222.613286405623, 3406.3017081498233, 3498.1254686328416, 3570.285942811448, 3574.982125089358, 926.3978414030867, 1005.7938122684832, 1036.124474818777, 1083.4257843342962, 1094.2121672395863, 10269.89730102697, 10355.326903751233, 10363.585456581728, 10401.947990260076, 10452.216083793746, 4505.346724724896, 4578.519255478127, 4653.278741795028, 4660.557247061523, 4748.548887855648, 9525.14273715369, 9590.66432674867, 9593.232541396672, 9619.422834629944, 9637.325387222967, 5237.371515418175, 5252.422233554306, 5252.606054545924, 5255.605829562722, 5261.447548007404, 4279.764612946318, 4301.204277208726, 4721.976390118028, 4758.1673207188605, 4788.640851936118, 3845.69234461248, 3881.2819628368843, 3935.193285376491, 3963.3460479020637, 3999.460072890181, 1254.9623511294676, 1281.1928485054357, 1290.9548165814151, 1291.3607764156802, 1295.0999055656227, 689.5104475822222, 754.2080815144077, 879.9604017819214, 909.9090090990848, 917.7246825952187, 5484.341878974548, 5580.497755202051, 5638.661680299113, 5709.457601527848, 5758.596898217084, 886.5389997201419, 928.7863791328033, 936.3726303376754, 943.2689665509286, 952.9618815247312, 3246.513023046562, 3788.4995902704636, 3805.505284313077, 3844.115853353744, 3848.249591329664, 9736.513174341255, 10221.846672299922, 10222.080012798884, 10232.846507302396, 10244.385336879812, 10173.643922462677, 10181.592736290613, 10183.134433573081, 10200.897991020143, 10220.386776793417, 6155.784547540815, 7753.418493613001, 7805.882911756442, 7877.094134174528, 7921.841563168704, 5687.289088863892, 5693.089105743061, 5824.621399609012, 5826.067829147316, 5884.235049443545, 9239.491827949527, 9307.488088155216, 9311.161995420445, 9366.297527685314, 9428.340016199074, 6853.725850965906, 8791.5164665944, 9463.053694630757, 9590.568424421057, 9604.615815367308, 6469.061986012096, 6730.764423245163, 6812.386885180357, 7042.260562640849, 7169.533980291264, 7696.653650585625, 7897.0, 7901.407394445555, 7903.446758727064, 7905.169957154416, 5755.913661295001, 5784.132380143013, 6825.2833452486775, 7007.334303241184, 7073.434125270069, 5816.8947899898885, 6655.367740064686, 6899.17209934811, 6943.479239057192, 6947.340002699735, 5446.809361672323, 6425.0, 6469.547131700924, 6518.380753828379, 6570.244421891352, 4693.131770622309, 4738.170820106398, 4785.401824771903, 4868.829590964299, 4962.354893863775, 4829.589484893823, 4866.124097662455, 4897.657163998629, 4899.216125419915, 4939.283803848493], "value": 4384.60975515237, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.0801866103114, 307.35148835956113, 307.53408585992213, 317.2876891378855, 318.53493898907595, 5081.517255860692, 5348.46515348465, 5502.889942201158, 5519.0, 5553.833384998451, 4754.9524504754945, 5041.017001684664, 5583.246261754662, 5588.329400471949, 5597.2498426054535, 3609.783412995229, 4188.120494696117, 4226.788660566967, 4245.448091748069, 4265.1469706058915, 3808.352580061396, 3896.103896103898, 4198.454200953872, 4256.127493863766, 4258.233517966764, 3315.519249708791, 3676.9448458273146, 4165.500139983191, 4175.248455278049, 4195.538490766014, 4155.522114956773, 4179.426590265325, 4188.958110418887, 4191.7694526801015, 4209.116085622023, 3332.333533293351, 3469.4795780632853, 3612.356377848073, 3622.2755448910325, 3650.8539658413633, 4897.559219670246, 4908.993656300449, 5305.628605997582, 5373.372973243085, 5464.060258608177, 4067.8983025424304, 4578.656600754956, 4781.163296423112, 4801.863170888956, 4807.399075115611, 4868.683535570177, 5850.67050382718, 5876.7355469004, 5889.8822023559715, 5934.8813023739285, 3647.8326935380687, 3719.0516418313427, 3907.648311651965, 3934.0, 3978.5424676162033, 4914.975425122887, 5784.334801497839, 5841.941580584183, 5927.762889484416, 6202.751889924398, 3637.3452778499595, 3856.2287542491613, 3882.689384849262, 4064.451299074587, 4086.2849001425225, 3746.763568022546, 3976.1053762903116, 4088.3049881520215, 4094.9385759213637, 4127.711060225755, 4253.680973926967, 4751.3348131261655, 4789.664723469394, 4807.014562014779, 4832.540908613676, 5891.911621325684, 5941.851453713692, 5945.910811337833, 5984.311804142492, 6003.669798161056, 1098.8352346512722, 1262.6275248801626, 1317.617890811654, 1318.1168617026615, 1322.4511827591634, 798.6086817459403, 864.8789169516273, 883.4566741453959, 896.6326352312645, 897.5512243878061, 4129.97935010323, 5145.202493613496, 5234.424213336606, 5270.552003079704, 5274.97362513185, 4843.588294994895, 5335.039692855324, 5737.971310143423, 5753.33836608787, 5791.681457519877, 4296.2266791977745, 4381.343401426912, 4507.0, 5065.797368105308, 5243.187305967581, 2823.054276817248, 2983.4331477019296, 2990.1627544287635, 3005.969940300613, 3044.375902939892, 5397.3523177218985, 5478.58910581708, 6054.152418661391, 6057.213122128983, 6081.5742897996715, 5049.469805670426, 5773.824396581947, 5804.6807425592, 5847.44449277318, 5911.876743418823, 5229.849433124783, 5295.576353891756, 5537.957291177463, 5611.607187496836, 6068.787592434332, 4939.48135445773, 5237.895242095061, 5285.418603953485, 5565.6104072714525, 5689.0, 4902.3872015998, 5291.523762861361, 5642.971785141048, 5684.261046063985, 5752.884942301047, 5326.946730532645, 5794.768209271582, 6009.0, 6082.482988945989, 6092.725827337869, 3459.4118999770603, 3659.323025240339, 3764.6235376462137, 3845.442410850467, 3850.7304488686646, 5041.521055499722, 5148.562372198405, 5473.452654734654, 5752.856178595569, 5836.883262334814, 4995.8517837328845, 5771.7114144293455, 5946.226990491208, 5958.106284057426, 6020.367861374668, 5929.02171141774, 5965.761369545169, 5978.521718262616, 5979.940200597938, 6019.367966363468, 5213.791448342024, 5450.974709036523, 5609.859753506032, 5824.912626310694, 5858.677772722541, 5119.539241468286, 5383.7308134593895, 5418.773356781717, 5979.312379076462, 5986.8026394720355, 5452.809151679672, 5822.359540450462, 5829.417058294137, 5845.736941837713, 5848.9179501792305], "value": 4154.588876944064, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.4886665872732, 630.4798541203509, 650.7201903181633, 743.0785825576287, 762.9771106866792, 10986.0, 14568.490102846403, 14762.704745905075, 14788.926055369735, 14793.630159246024, 11059.944700276488, 13294.202347859135, 13368.331583420839, 13417.262050587215, 13881.93059034706, 578.4158000419585, 787.6652422720349, 815.8857759913617, 818.6848063495538, 822.1244374740882, 793.4247670438914, 808.9797755056142, 810.7891948093509, 811.8944537210184, 813.093400858042, 831.4553967151526, 843.7215718812777, 856.0968178571604, 856.8500512410335, 858.0818524179131, 832.3133414932644, 844.3245403677065, 854.747849384433, 857.1171693156059, 860.3633311349619, 630.8170630517144, 636.0491065856575, 638.141699414287, 638.7604648256904, 639.6545865232769, 12094.09294302922, 14255.28723563828, 14265.787408070326, 14340.856591434056, 14341.56975290743, 11169.553217871275, 13087.47650093995, 13153.342332883318, 13283.535076272285, 13362.398692058881, 10968.725781855437, 11042.33745975244, 11090.669119705606, 11190.608328708457, 11571.42142892852, 3507.228409749851, 3550.218951830593, 3601.477785721082, 3674.401635288658, 3730.8694195703292, 88520.5573972126, 89723.65414518722, 89922.10077899203, 90263.0, 92806.07193928042, 10038.849417258747, 10239.436830974219, 10242.815927289783, 10293.279470436997, 10336.586536538454, 16059.759103613454, 18487.53781155482, 19777.307794227134, 19797.109130089182, 20002.299919502748, 7571.962140189264, 7602.1257555380735, 7735.690572377154, 7828.373730101578, 7837.608119593998, 4204.558521355275, 4231.661467082626, 4247.787610619456, 4261.21167583998, 4349.151915376535, 3400.6939375456327, 3486.3550243205086, 3516.9824150879595, 3542.6634469725336, 3543.6102028776813, 639.114825966031, 646.1341801985362, 647.7020570537605, 651.8956966885276, 667.9732810687518, 514.2209552527924, 552.4392741367515, 558.9301337332834, 584.4360192414256, 614.0543562913064, 7951.324137448266, 9022.375972325044, 9027.909720902708, 9120.954395228115, 9188.035256298126, 571.4713889652138, 572.842468321215, 600.9663378988133, 622.8723111762168, 632.718440294074, 4282.828686852491, 4455.532169122261, 4483.2826747720865, 4498.460184777848, 4580.496145424001, 73547.0, 74351.25648743444, 106902.0, 107631.46184269029, 120654.0, 55447.44552554504, 77729.61135194288, 81381.0, 98573.50713246388, 99623.00376996282, 62892.68553657294, 83746.0, 84358.0, 84659.5767021161, 85958.0, 9065.002849686398, 9472.05897001699, 9780.065980205767, 9820.067093626094, 9846.901530984598, 50794.74602626963, 50930.453477325245, 51069.0, 51625.48374516357, 52300.0, 73691.6315418441, 75715.0, 75939.0, 76731.0, 78923.0, 12130.0, 12228.0, 12311.938440307742, 12440.688982775502, 12592.181508201938, 13861.584152475441, 13911.582652520441, 14013.43946242139, 14166.929165354108, 14167.504137355143, 12785.232886026868, 12792.04059695508, 12864.228146310877, 12873.356332183166, 12920.612381628565, 12762.872371276168, 12981.480740770263, 13040.804387933998, 13077.0, 13224.0, 12094.334811585448, 12477.5008999639, 12499.93750031244, 12931.418086185957, 13215.60353189406, 10569.788604228026, 10589.682309530726, 11123.777524449628, 11514.654560363202, 11578.71053223676, 11005.394703291397, 11201.439928003405, 11328.716782080184, 11457.0, 11659.650210493699], "value": 7118.542197536123, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1601.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 117.0, 119.0, 116.0, 117.0, 141.0, 120.0, 115.0, 119.0], "value": 118.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 111.0, 146.0, 140.0, 150.0, 154.0, 114.0, 137.0, 114.0], "value": 132.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 200.0, 197.0, 200.0, 268.0, 248.0, 198.0, 204.0, 198.0, 196.0], "value": 210.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 59.0, 89.0, 60.0, 61.0, 58.0, 58.0, 78.0, 57.0], "value": 65.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 69.0, 89.0, 75.0, 70.0, 70.0, 88.0, 71.0, 71.0, 89.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 131.0, 89.0, 87.0, 89.0, 92.0, 89.0, 86.0, 89.0, 86.0], "value": 97.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 159.0, 126.0, 172.0, 139.0, 131.0, 170.0, 107.0, 115.0, 150.0], "value": 136.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 92.0, 118.0, 137.0, 131.0, 138.0, 88.0, 114.0, 81.0, 72.0], "value": 105.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 56.0, 76.0, 61.0, 60.0, 62.0, 61.0, 60.0, 59.0, 59.0], "value": 60.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 67.0, 82.0, 74.0, 70.0, 74.0, 72.0, 72.0, 72.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 77.0, 99.0, 101.0, 82.0, 84.0, 83.0, 84.0, 94.0, 85.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 159.0, 154.0, 150.0, 161.0, 156.0, 159.0, 166.0, 190.0, 276.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 168.0, 165.0, 171.0, 227.0, 162.0, 206.0, 225.0, 238.0, 168.0], "value": 190.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 66.0, 69.0, 65.0, 81.0, 85.0, 92.0, 87.0], "value": 74.9, "unit": "score"}]}]} 08:45:56 INFO - Return code: 0 08:45:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:45:56 ERROR - # TBPL SUCCESS # 08:45:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:45:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:45:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:45:56 INFO - 2016-12-26 08:45:56.538 screenresolution[1672:11598] starting screenresolution argv=screenresolution get 08:45:56 INFO - 2016-12-26 08:45:56.542 screenresolution[1672:11598] Display 0: 1600x1200x32@60 08:45:56 INFO - 2016-12-26 08:45:56.548 screenresolution[1673:11601] starting screenresolution argv=screenresolution list 08:45:56 INFO - Available Modes on Display 0 08:45:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:45:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:45:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:45:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:45:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:45:56 INFO - Intel Iris: 08:45:56 INFO - Chipset Model: Intel Iris 08:45:56 INFO - Type: GPU 08:45:56 INFO - Bus: Built-In 08:45:56 INFO - VRAM (Dynamic, Max): 1536 MB 08:45:56 INFO - Vendor: Intel (0x8086) 08:45:56 INFO - Device ID: 0x0a2e 08:45:56 INFO - Revision ID: 0x0009 08:45:56 INFO - Displays: 08:45:56 INFO - Display: 08:45:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:45:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:45:56 INFO - Display Serial Number: 1600x1200 60 08:45:56 INFO - Main Display: Yes 08:45:56 INFO - Mirror: Off 08:45:56 INFO - Online: Yes 08:45:56 INFO - Rotation: Supported 08:45:56 INFO - Return code: 0 08:45:56 INFO - Running post-action listener: _resource_record_post_action 08:45:56 INFO - [mozharness: 2016-12-26 16:45:56.788788Z] Finished run-tests step (success) 08:45:56 INFO - Running post-run listener: _resource_record_post_run 08:45:56 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 33550848; Write bytes: 664040960; Read time: 208; Write time: 1960 08:45:56 INFO - TinderboxPrint: CPU usage
54.5% 08:45:56 INFO - TinderboxPrint: I/O read bytes / time
33,550,848 / 208 08:45:56 INFO - TinderboxPrint: I/O write bytes / time
664,040,960 / 1,960 08:45:56 INFO - TinderboxPrint: CPU idle
2,206.2 (45.5%) 08:45:56 INFO - TinderboxPrint: CPU system
974.0 (20.1%) 08:45:56 INFO - TinderboxPrint: CPU user
1,665.4 (34.4%) 08:45:56 INFO - TinderboxPrint: Swap in / out
379,195,392 / 0 08:45:56 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 336665088; Write bytes: 334129152; Read time: 27138; Write time: 720 08:45:56 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 32625152; Write bytes: 312217088; Read time: 196; Write time: 1185 08:45:57 INFO - Running post-run listener: _upload_blobber_files 08:45:57 INFO - Blob upload gear active. 08:45:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:45:57 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:45:57 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:45:57 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:45:57 INFO - (blobuploader) - INFO - Open directory for files ... 08:45:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:45:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:45:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:58 INFO - (blobuploader) - INFO - Done attempting. 08:45:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:45:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:45:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:59 INFO - (blobuploader) - INFO - Done attempting. 08:45:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:45:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:46:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:46:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:46:01 INFO - (blobuploader) - INFO - Done attempting. 08:46:01 INFO - (blobuploader) - INFO - Iteration through files over. 08:46:01 INFO - Return code: 0 08:46:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:46:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:46:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/549cd36933f1d69a0ed75aad1e3f7875e3f217220a09fef28e8e96abb16bf9775cc9bbaad76dc09987f5247fa70d2db30807f06007147231705c6da581a7253a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2158f7cf4a67960db1625055dae92ec96c6dccb322c1ccca7f353bbf62a616ed35994300ddd8e4b93814b9472530793ee880e9da7ec30d54d44278a291f769c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/98c122417f10adf15f5f93cf17ebbb99cadfd74a6d1df6dd8da75551d6736dbc77bd6a022b26720c7f44b0bb2cdf9263afa530556e87dfa9e334f9eb0b9bcd8b"} 08:46:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:46:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:46:01 INFO - Contents: 08:46:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/549cd36933f1d69a0ed75aad1e3f7875e3f217220a09fef28e8e96abb16bf9775cc9bbaad76dc09987f5247fa70d2db30807f06007147231705c6da581a7253a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2158f7cf4a67960db1625055dae92ec96c6dccb322c1ccca7f353bbf62a616ed35994300ddd8e4b93814b9472530793ee880e9da7ec30d54d44278a291f769c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/98c122417f10adf15f5f93cf17ebbb99cadfd74a6d1df6dd8da75551d6736dbc77bd6a022b26720c7f44b0bb2cdf9263afa530556e87dfa9e334f9eb0b9bcd8b"} 08:46:01 INFO - Running post-run listener: copy_logs_to_upload_dir 08:46:01 INFO - Copying logs to upload dir... 08:46:01 INFO - mkdir: /builds/slave/test/build/upload/logs 08:46:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.778834 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-26 08:46:01.515469) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 08:46:01.519556) ========= 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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners TMPDIR=/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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/549cd36933f1d69a0ed75aad1e3f7875e3f217220a09fef28e8e96abb16bf9775cc9bbaad76dc09987f5247fa70d2db30807f06007147231705c6da581a7253a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2158f7cf4a67960db1625055dae92ec96c6dccb322c1ccca7f353bbf62a616ed35994300ddd8e4b93814b9472530793ee880e9da7ec30d54d44278a291f769c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/98c122417f10adf15f5f93cf17ebbb99cadfd74a6d1df6dd8da75551d6736dbc77bd6a022b26720c7f44b0bb2cdf9263afa530556e87dfa9e334f9eb0b9bcd8b"} build_url:https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011024 build_url: 'https://queue.taskcluster.net/v1/task/KWnO2ZQ4QPi2Dlkf_bVkmg/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/549cd36933f1d69a0ed75aad1e3f7875e3f217220a09fef28e8e96abb16bf9775cc9bbaad76dc09987f5247fa70d2db30807f06007147231705c6da581a7253a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2158f7cf4a67960db1625055dae92ec96c6dccb322c1ccca7f353bbf62a616ed35994300ddd8e4b93814b9472530793ee880e9da7ec30d54d44278a291f769c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/98c122417f10adf15f5f93cf17ebbb99cadfd74a6d1df6dd8da75551d6736dbc77bd6a022b26720c7f44b0bb2cdf9263afa530556e87dfa9e334f9eb0b9bcd8b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 08:46:01.556063) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 08:46:01.556705) ========= 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.DA3Lf5pwgi/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.GchF0pPrvf/Listeners TMPDIR=/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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.004895 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 08:46:01.608542) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 08:46:01.609102) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 08:46:01.609612) ========= ========= Total master_lag: 0.20 =========