builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0077 starttime: 1480868275.89 results: success (0) buildid: 20161204063138 builduid: 97497859672e44a082f22d047567b14e revision: d10b8f6cce8d37787855c210e9b9f8110ceb45d1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-04 08:17:55.894009) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-04 08:17:55.894528) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-04 08:17:55.894850) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-04 08:17:55.921743) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 08:17:55.922236) ========= 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.4bbo1fn3AR/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.mNwztwvZVH/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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-04 08:17:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.5M=0.001s 2016-12-04 08:17:56 (16.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.794792 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 08:17:56.742940) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 08:17:56.743299) ========= 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.4bbo1fn3AR/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.mNwztwvZVH/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.026518 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 08:17:56.792172) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-04 08:17:56.792558) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d10b8f6cce8d37787855c210e9b9f8110ceb45d1 --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 d10b8f6cce8d37787855c210e9b9f8110ceb45d1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4bbo1fn3AR/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.mNwztwvZVH/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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-04 08:17:56,887 truncating revision to first 12 chars 2016-12-04 08:17:56,887 Setting DEBUG logging. 2016-12-04 08:17:56,887 attempt 1/10 2016-12-04 08:17:56,887 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d10b8f6cce8d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-04 08:17:58,013 unpacking tar archive at: mozilla-aurora-d10b8f6cce8d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.391508 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-04 08:17:58.208066) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-04 08:17:58.208440) ========= script_repo_revision: d10b8f6cce8d37787855c210e9b9f8110ceb45d1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-04 08:17:58.208941) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-04 08:17:58.209262) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-04 08:17:58.229560) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-04 08:17:58.229913) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4bbo1fn3AR/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.mNwztwvZVH/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:17:58 INFO - MultiFileLogger online at 20161204 08:17:58 in /builds/slave/test 08:17:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:17:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:17:58 INFO - {'append_to_log': False, 08:17:58 INFO - 'base_work_dir': '/builds/slave/test', 08:17:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:17:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:17:58 INFO - 'branch': 'Mozilla-Aurora', 08:17:58 INFO - 'buildbot_json_path': 'buildprops.json', 08:17:58 INFO - 'config_files': ('talos/mac_config.py',), 08:17:58 INFO - 'default_actions': ('clobber', 08:17:58 INFO - 'read-buildbot-config', 08:17:58 INFO - 'download-and-extract', 08:17:58 INFO - 'populate-webroot', 08:17:58 INFO - 'create-virtualenv', 08:17:58 INFO - 'install', 08:17:58 INFO - 'run-tests'), 08:17:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:17:58 INFO - 'download_minidump_stackwalk': True, 08:17:58 INFO - 'download_symbols': 'ondemand', 08:17:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:17:58 INFO - 'tooltool.py': '/tools/tooltool.py', 08:17:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:17:58 INFO - '/tools/misc-python/virtualenv.py')}, 08:17:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:17:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:17:58 INFO - 'installer_path': 'installer.exe', 08:17:58 INFO - 'log_level': 'info', 08:17:58 INFO - 'log_name': 'talos', 08:17:58 INFO - 'log_to_console': True, 08:17:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:17:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:17:58 INFO - 'opt_config_files': (), 08:17:58 INFO - 'pip_index': False, 08:17:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:17:58 INFO - 'cmd': ('bash', 08:17:58 INFO - '-c', 08:17:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:17:58 INFO - 'enabled': True, 08:17:58 INFO - 'halt_on_failure': False, 08:17:58 INFO - 'name': 'check_screen_resolution'},), 08:17:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:17:58 INFO - 'cmd': ('bash', 08:17:58 INFO - '-c', 08:17:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:17:58 INFO - 'enabled': True, 08:17:58 INFO - 'halt_on_failure': False, 08:17:58 INFO - 'name': 'check_screen_resolution'},), 08:17:58 INFO - 'run_cmd_checks_enabled': True, 08:17:58 INFO - 'sps_profile': False, 08:17:58 INFO - 'sps_profile_interval': 0, 08:17:58 INFO - 'suite': 'dromaeojs', 08:17:58 INFO - 'system_bits': '32', 08:17:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:17:58 INFO - 'title': 't-yosemite-r7-0077', 08:17:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:17:58 INFO - 'use_talos_json': True, 08:17:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:17:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:17:58 INFO - 'work_dir': 'build'} 08:17:58 INFO - [mozharness: 2016-12-04 16:17:58.438292Z] Running clobber step. 08:17:58 INFO - Running pre-action listener: _resource_record_pre_action 08:17:58 INFO - Running main action method: clobber 08:17:58 INFO - rmtree: /builds/slave/test/build 08:17:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:18:00 INFO - Running post-action listener: _resource_record_post_action 08:18:00 INFO - [mozharness: 2016-12-04 16:18:00.906470Z] Finished clobber step (success) 08:18:00 INFO - [mozharness: 2016-12-04 16:18:00.906652Z] Running read-buildbot-config step. 08:18:00 INFO - Running pre-action listener: _resource_record_pre_action 08:18:00 INFO - Running main action method: read_buildbot_config 08:18:00 INFO - Using buildbot properties: 08:18:00 INFO - { 08:18:00 INFO - "project": "", 08:18:00 INFO - "product": "firefox", 08:18:00 INFO - "script_repo_revision": "production", 08:18:00 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:18:00 INFO - "repository": "", 08:18:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:18:00 INFO - "buildid": "20161204063138", 08:18:00 INFO - "pgo_build": "False", 08:18:00 INFO - "basedir": "/builds/slave/test", 08:18:00 INFO - "buildnumber": 54, 08:18:00 INFO - "slavename": "t-yosemite-r7-0077", 08:18:00 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:18:00 INFO - "platform": "yosemite_r7", 08:18:00 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:18:00 INFO - "branch": "mozilla-aurora", 08:18:00 INFO - "repo_path": "releases/mozilla-aurora", 08:18:00 INFO - "revision": "d10b8f6cce8d37787855c210e9b9f8110ceb45d1", 08:18:00 INFO - "stage_platform": "macosx64", 08:18:00 INFO - "builduid": "97497859672e44a082f22d047567b14e", 08:18:00 INFO - "slavebuilddir": "test" 08:18:00 INFO - } 08:18:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:18:00 INFO - Running post-action listener: _resource_record_post_action 08:18:00 INFO - [mozharness: 2016-12-04 16:18:00.910006Z] Finished read-buildbot-config step (success) 08:18:00 INFO - [mozharness: 2016-12-04 16:18:00.910096Z] Running download-and-extract step. 08:18:00 INFO - Running pre-action listener: _resource_record_pre_action 08:18:00 INFO - Running main action method: download_and_extract 08:18:00 INFO - mkdir: /builds/slave/test/build/tests 08:18:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:00 INFO - trying https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:18:00 INFO - Downloading https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/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:18:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/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:18:01 INFO - Downloaded 1199 bytes. 08:18:01 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:18:01 INFO - Using the following test package requirements: 08:18:01 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:18:01 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:18:01 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:18:01 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:18:01 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:18:01 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:18:01 INFO - u'jsshell-mac.zip'], 08:18:01 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:18:01 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:18:01 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:18:01 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:18:01 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:18:01 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:18:01 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:18:01 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:18:01 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:18:01 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:18:01 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:18:01 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:18:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:18:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:18:01 INFO - Fetch https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:18:02 INFO - Http code: 200 08:18:02 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 08:18:02 INFO - x-amz-cf-id: lk3Le1taaUBCR5_Am2eS8owyLW_PpHGSKeSb0eF-zg-z4GCCleHjmA== 08:18:02 INFO - x-amz-version-id: pxcMO73X4XLheDmPufcXp6Sy74BT4740 08:18:02 INFO - Expected file size: 16281985 08:18:02 INFO - Obtained file size: 16281985 08:18:09 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:18:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:18:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:18:09 INFO - Fetch https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:18:10 INFO - Http code: 200 08:18:10 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 08:18:10 INFO - x-amz-cf-id: M--GXY5WIqSplPM-ncsMOsci1i8whtxLrxREQNbxxZ-yuW0KFYARcw== 08:18:10 INFO - x-amz-version-id: pxcMO73X4XLheDmPufcXp6Sy74BT4740 08:18:10 INFO - Expected file size: 16281985 08:18:10 INFO - Obtained file size: 16281985 08:18:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:18:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:18:16 INFO - Fetch https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:18:17 INFO - Http code: 200 08:18:17 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 08:18:17 INFO - x-amz-cf-id: EryEmHkMo5fw_zDarV56EwCZN3r_H_wkIg0j3_Fo4Bxy_P8ozKz27g== 08:18:17 INFO - x-amz-version-id: XDaQZZKHb8Qrb8mqqlryclA4nAoO_mm2 08:18:17 INFO - Expected file size: 14409038 08:18:17 INFO - Obtained file size: 14409038 08:18:17 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:18:17 INFO - trying https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:18:17 INFO - Downloading https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:18:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/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:18:20 INFO - Downloaded 109762909 bytes. 08:18:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:18:20 INFO - mkdir: /builds/slave/test/properties 08:18:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:18:20 INFO - Writing to file /builds/slave/test/properties/build_url 08:18:20 INFO - Contents: 08:18:20 INFO - build_url:https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:18:21 INFO - Running post-action listener: _resource_record_post_action 08:18:21 INFO - Running post-action listener: set_extra_try_arguments 08:18:21 INFO - [mozharness: 2016-12-04 16:18:21.459859Z] Finished download-and-extract step (success) 08:18:21 INFO - [mozharness: 2016-12-04 16:18:21.459979Z] Running populate-webroot step. 08:18:21 INFO - Running pre-action listener: _resource_record_pre_action 08:18:21 INFO - Running main action method: populate_webroot 08:18:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:18:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:18:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:18:21 INFO - '--tppagecycles', 08:18:21 INFO - '7'], 08:18:21 INFO - 'tests': ['tsvgm']}}, 08:18:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:18:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:18:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:18:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:18:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:18:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:18:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:21 INFO - 'talos_options': ['--disable-e10s'], 08:18:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:18:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:18:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:21 INFO - 'talos_options': ['--disable-e10s'], 08:18:21 INFO - 'tests': ['damp', 'tps']}, 08:18:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:21 INFO - 'tests': ['damp', 'tps']}, 08:18:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:18:21 INFO - 'tests': ['dromaeo_dom']}, 08:18:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:18:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:18:21 INFO - 'tests': ['basic_compositor_video']}, 08:18:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:18:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:18:21 INFO - 'tests': ['a11yr', 08:18:21 INFO - 'ts_paint', 08:18:21 INFO - 'tpaint', 08:18:21 INFO - 'sessionrestore', 08:18:21 INFO - 'sessionrestore_no_auto_restore', 08:18:21 INFO - 'tabpaint']}, 08:18:21 INFO - 'other-e10s': {'tests': ['a11yr', 08:18:21 INFO - 'ts_paint', 08:18:21 INFO - 'tpaint', 08:18:21 INFO - 'sessionrestore', 08:18:21 INFO - 'sessionrestore_no_auto_restore', 08:18:21 INFO - 'tabpaint']}, 08:18:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:18:21 INFO - 'ts_paint', 08:18:21 INFO - 'tpaint', 08:18:21 INFO - 'sessionrestore', 08:18:21 INFO - 'sessionrestore_no_auto_restore', 08:18:21 INFO - 'tabpaint']}, 08:18:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:18:21 INFO - 'ts_paint', 08:18:21 INFO - 'tpaint', 08:18:21 INFO - 'sessionrestore', 08:18:21 INFO - 'sessionrestore_no_auto_restore', 08:18:21 INFO - 'tabpaint']}, 08:18:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:18:21 INFO - 'tests': ['a11yr', 08:18:21 INFO - 'ts_paint', 08:18:21 INFO - 'tpaint', 08:18:21 INFO - 'sessionrestore', 08:18:21 INFO - 'sessionrestore_no_auto_restore', 08:18:21 INFO - 'tabpaint']}, 08:18:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:18:21 INFO - 'tests': ['a11yr', 08:18:21 INFO - 'ts_paint', 08:18:21 INFO - 'tpaint', 08:18:21 INFO - 'sessionrestore', 08:18:21 INFO - 'sessionrestore_no_auto_restore', 08:18:21 INFO - 'tabpaint']}, 08:18:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:18:21 INFO - 'tests': ['tsvgx', 08:18:21 INFO - 'tsvgr_opacity', 08:18:21 INFO - 'tart', 08:18:21 INFO - 'tscrollx', 08:18:21 INFO - 'cart']}, 08:18:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:18:21 INFO - 'tsvgr_opacity', 08:18:21 INFO - 'tart', 08:18:21 INFO - 'tscrollx', 08:18:21 INFO - 'cart']}, 08:18:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:21 INFO - 'talos_options': ['--disable-e10s'], 08:18:21 INFO - 'tests': ['tp5o']}, 08:18:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:21 INFO - 'tests': ['tp5o']}, 08:18:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:21 INFO - 'talos_options': ['--disable-e10s', 08:18:21 INFO - '--xperf_path', 08:18:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:18:21 INFO - 'tests': ['tp5n']}, 08:18:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:21 INFO - 'talos_options': ['--xperf_path', 08:18:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:18:21 INFO - 'tests': ['tp5n']}}, 08:18:21 INFO - 'talos.zip': {'path': '', 08:18:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:18:21 INFO - Running post-action listener: _resource_record_post_action 08:18:21 INFO - [mozharness: 2016-12-04 16:18:21.478833Z] Finished populate-webroot step (success) 08:18:21 INFO - [mozharness: 2016-12-04 16:18:21.478950Z] Running create-virtualenv step. 08:18:21 INFO - Running pre-action listener: _resource_record_pre_action 08:18:21 INFO - Running main action method: create_virtualenv 08:18:21 INFO - Creating virtualenv /builds/slave/test/build/venv 08:18:21 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:18:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:18:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:18:21 INFO - Using real prefix '/tools/python27' 08:18:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:18:22 INFO - Installing distribute.............................................................................................................................................................................................done. 08:18:25 INFO - Installing pip.................done. 08:18:25 INFO - Return code: 0 08:18:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:18:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:18:25 INFO - Reading from file tmpfile_stdout 08:18:25 INFO - Output received: 08:18:25 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:18:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:18: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:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18: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:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a165490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8869c776d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f79d30>, '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 0x109ddfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f71b20>, '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.4bbo1fn3AR/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.mNwztwvZVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:18: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', 'pip>=1.5'] in /builds/slave/test/build 08:18: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 pip>=1.5 08:18:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4bbo1fn3AR/Render', 08:18:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:25 INFO - 'HOME': '/Users/cltbld', 08:18:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:25 INFO - 'LOGNAME': 'cltbld', 08:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:25 INFO - 'MOZ_NO_REMOTE': '1', 08:18:25 INFO - 'NO_EM_RESTART': '1', 08:18:25 INFO - 'PAGER': '/bin/cat', 08:18:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:25 INFO - 'PWD': '/builds/slave/test', 08:18:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:25 INFO - 'SHELL': '/bin/bash', 08:18:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mNwztwvZVH/Listeners', 08:18:25 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:18:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:25 INFO - 'USER': 'cltbld', 08:18:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:25 INFO - 'XPC_FLAGS': '0x0', 08:18:25 INFO - 'XPC_SERVICE_NAME': '0', 08:18:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:25 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:18:25 INFO - Cleaning up... 08:18:25 INFO - Return code: 0 08:18:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:18: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:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18: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:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18: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', '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 0x10a165490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8869c776d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f79d30>, '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 0x109ddfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f71b20>, '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.4bbo1fn3AR/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.mNwztwvZVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:18: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:18: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 psutil>=3.1.1 08:18:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4bbo1fn3AR/Render', 08:18:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:25 INFO - 'HOME': '/Users/cltbld', 08:18:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:25 INFO - 'LOGNAME': 'cltbld', 08:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:25 INFO - 'MOZ_NO_REMOTE': '1', 08:18:25 INFO - 'NO_EM_RESTART': '1', 08:18:25 INFO - 'PAGER': '/bin/cat', 08:18:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:25 INFO - 'PWD': '/builds/slave/test', 08:18:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:25 INFO - 'SHELL': '/bin/bash', 08:18:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mNwztwvZVH/Listeners', 08:18:25 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:18:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:25 INFO - 'USER': 'cltbld', 08:18:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:25 INFO - 'XPC_FLAGS': '0x0', 08:18:25 INFO - 'XPC_SERVICE_NAME': '0', 08:18:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:25 INFO - Downloading/unpacking psutil>=3.1.1 08:18: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:18: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:18: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:18: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:18: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:18: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:18:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:18:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:18:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:18:29 INFO - Installing collected packages: psutil 08:18:29 INFO - Running setup.py install for psutil 08:18:30 INFO - building 'psutil._psutil_osx' extension 08:18:30 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:18:30 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:18:30 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:18:30 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:18:30 INFO - building 'psutil._psutil_posix' extension 08:18:30 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:18:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:18:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:18:30 INFO - ^ 08:18:30 INFO - 1 warning generated. 08:18:30 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:18:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:18:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:18:31 INFO - Successfully installed psutil 08:18:31 INFO - Cleaning up... 08:18:31 INFO - Return code: 0 08:18:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:18:31 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:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:31 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:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:31 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 0x10a165490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8869c776d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f79d30>, '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 0x109ddfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f71b20>, '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.4bbo1fn3AR/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.mNwztwvZVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:18:31 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:18:31 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:18:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4bbo1fn3AR/Render', 08:18:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:31 INFO - 'HOME': '/Users/cltbld', 08:18:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:31 INFO - 'LOGNAME': 'cltbld', 08:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:31 INFO - 'MOZ_NO_REMOTE': '1', 08:18:31 INFO - 'NO_EM_RESTART': '1', 08:18:31 INFO - 'PAGER': '/bin/cat', 08:18:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:31 INFO - 'PWD': '/builds/slave/test', 08:18:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:31 INFO - 'SHELL': '/bin/bash', 08:18:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mNwztwvZVH/Listeners', 08:18:31 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:18:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:31 INFO - 'USER': 'cltbld', 08:18:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:31 INFO - 'XPC_FLAGS': '0x0', 08:18:31 INFO - 'XPC_SERVICE_NAME': '0', 08:18:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:18:31 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:18:31 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:18:31 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:18:31 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:18:31 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:18:31 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:18:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:18:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:18:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:18:34 INFO - Installing collected packages: mozsystemmonitor 08:18:34 INFO - Running setup.py install for mozsystemmonitor 08:18:34 INFO - Successfully installed mozsystemmonitor 08:18:34 INFO - Cleaning up... 08:18:34 INFO - Return code: 0 08:18:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:18: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:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18: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:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:34 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 0x10a165490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8869c776d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f79d30>, '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 0x109ddfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f71b20>, '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.4bbo1fn3AR/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.mNwztwvZVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:18:34 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:18:34 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:18:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4bbo1fn3AR/Render', 08:18:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:34 INFO - 'HOME': '/Users/cltbld', 08:18:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:34 INFO - 'LOGNAME': 'cltbld', 08:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:34 INFO - 'MOZ_NO_REMOTE': '1', 08:18:34 INFO - 'NO_EM_RESTART': '1', 08:18:34 INFO - 'PAGER': '/bin/cat', 08:18:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:34 INFO - 'PWD': '/builds/slave/test', 08:18:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:34 INFO - 'SHELL': '/bin/bash', 08:18:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mNwztwvZVH/Listeners', 08:18:34 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:18:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:34 INFO - 'USER': 'cltbld', 08:18:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:34 INFO - 'XPC_FLAGS': '0x0', 08:18:34 INFO - 'XPC_SERVICE_NAME': '0', 08:18:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:34 INFO - Downloading/unpacking jsonschema==2.5.1 08:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:18:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:18:37 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:18:37 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:18:37 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:18:37 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:18:37 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:18:37 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:18:37 INFO - Downloading functools32-3.2.3-2.tar.gz 08:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:18:37 INFO - warning: no files found matching '*.txt' 08:18:37 INFO - no previously-included directories found matching 'build' 08:18:37 INFO - no previously-included directories found matching 'dist' 08:18:37 INFO - no previously-included directories found matching '.git*' 08:18:37 INFO - Installing collected packages: jsonschema, functools32 08:18:37 INFO - Running setup.py install for functools32 08:18:37 INFO - warning: no files found matching '*.txt' 08:18:37 INFO - no previously-included directories found matching 'build' 08:18:37 INFO - no previously-included directories found matching 'dist' 08:18:37 INFO - no previously-included directories found matching '.git*' 08:18:37 INFO - Successfully installed jsonschema functools32 08:18:37 INFO - Cleaning up... 08:18:37 INFO - Return code: 0 08:18:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:37 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 0x10a165490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8869c776d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f79d30>, '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 0x109ddfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f71b20>, '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.4bbo1fn3AR/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.mNwztwvZVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:18:37 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:18:37 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:18:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4bbo1fn3AR/Render', 08:18:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:37 INFO - 'HOME': '/Users/cltbld', 08:18:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:37 INFO - 'LOGNAME': 'cltbld', 08:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:37 INFO - 'MOZ_NO_REMOTE': '1', 08:18:37 INFO - 'NO_EM_RESTART': '1', 08:18:37 INFO - 'PAGER': '/bin/cat', 08:18:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:37 INFO - 'PWD': '/builds/slave/test', 08:18:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:37 INFO - 'SHELL': '/bin/bash', 08:18:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mNwztwvZVH/Listeners', 08:18:37 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:18:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:37 INFO - 'USER': 'cltbld', 08:18:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:37 INFO - 'XPC_FLAGS': '0x0', 08:18:37 INFO - 'XPC_SERVICE_NAME': '0', 08:18:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:18:38 INFO - Cleaning up... 08:18:38 INFO - Return code: 0 08:18:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:18: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:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18: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:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:38 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 0x10a165490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8869c776d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f79d30>, '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 0x109ddfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f71b20>, '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.4bbo1fn3AR/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.mNwztwvZVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:18:38 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:18:38 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:18:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4bbo1fn3AR/Render', 08:18:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:38 INFO - 'HOME': '/Users/cltbld', 08:18:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:38 INFO - 'LOGNAME': 'cltbld', 08:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:38 INFO - 'MOZ_NO_REMOTE': '1', 08:18:38 INFO - 'NO_EM_RESTART': '1', 08:18:38 INFO - 'PAGER': '/bin/cat', 08:18:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:38 INFO - 'PWD': '/builds/slave/test', 08:18:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:38 INFO - 'SHELL': '/bin/bash', 08:18:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mNwztwvZVH/Listeners', 08:18:38 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:18:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:38 INFO - 'USER': 'cltbld', 08:18:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:38 INFO - 'XPC_FLAGS': '0x0', 08:18:38 INFO - 'XPC_SERVICE_NAME': '0', 08:18:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:38 INFO - Downloading/unpacking blobuploader==1.2.4 08:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:40 INFO - Downloading blobuploader-1.2.4.tar.gz 08:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:18:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:18:41 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:18:41 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:18:41 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:18:41 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:18:41 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:18:41 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:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:18:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:18:41 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:18:41 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:18:41 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:18:41 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:18:41 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:18:41 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:18:42 INFO - Downloading docopt-0.6.1.tar.gz 08:18:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:18:42 INFO - Installing collected packages: blobuploader, requests, docopt 08:18:42 INFO - Running setup.py install for blobuploader 08:18:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:18:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:18:42 INFO - Running setup.py install for requests 08:18:42 INFO - Running setup.py install for docopt 08:18:43 INFO - Successfully installed blobuploader requests docopt 08:18:43 INFO - Cleaning up... 08:18:43 INFO - Return code: 0 08:18:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:43 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 0x10a165490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8869c776d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f79d30>, '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 0x109ddfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f71b20>, '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.4bbo1fn3AR/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.mNwztwvZVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:18:43 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:18:43 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:18:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4bbo1fn3AR/Render', 08:18:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:43 INFO - 'HOME': '/Users/cltbld', 08:18:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:43 INFO - 'LOGNAME': 'cltbld', 08:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:43 INFO - 'MOZ_NO_REMOTE': '1', 08:18:43 INFO - 'NO_EM_RESTART': '1', 08:18:43 INFO - 'PAGER': '/bin/cat', 08:18:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:43 INFO - 'PWD': '/builds/slave/test', 08:18:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:43 INFO - 'SHELL': '/bin/bash', 08:18:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mNwztwvZVH/Listeners', 08:18:43 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:18:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:43 INFO - 'USER': 'cltbld', 08:18:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:43 INFO - 'XPC_FLAGS': '0x0', 08:18:43 INFO - 'XPC_SERVICE_NAME': '0', 08:18:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:18:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-3oIyFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:18:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-AbSr8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:18:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-yL4LiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:18:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-CipqT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:18:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Ynbrhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:18:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Ghcpav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:18:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-8GrzWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:18:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-FbZKUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:18:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-JTi8Jq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:18:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-KsinXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:18:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-RSL2Qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:18:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Tlk4Bf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:18:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-iW_cxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:18:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-VY2kb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:18:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-mbarJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:18:45 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-jw0n7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:18:45 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-tBipKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:18:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:18:45 INFO - Running setup.py install for manifestparser 08:18:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:18:45 INFO - Running setup.py install for mozcrash 08:18:45 INFO - Running setup.py install for mozdebug 08:18:45 INFO - Running setup.py install for mozdevice 08:18:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:18:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:18:45 INFO - Running setup.py install for mozfile 08:18:46 INFO - Running setup.py install for mozhttpd 08:18:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:18:46 INFO - Running setup.py install for mozinfo 08:18:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:18:46 INFO - Running setup.py install for mozInstall 08:18:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:18:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:18:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:18:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:18:46 INFO - Running setup.py install for mozleak 08:18:46 INFO - Running setup.py install for mozlog 08:18:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:18:46 INFO - Running setup.py install for moznetwork 08:18:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:18:46 INFO - Running setup.py install for mozprocess 08:18:47 INFO - Running setup.py install for mozprofile 08:18:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:18:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:18:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:18:47 INFO - Running setup.py install for mozrunner 08:18:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:18:47 INFO - Running setup.py install for mozscreenshot 08:18:47 INFO - Running setup.py install for moztest 08:18:47 INFO - Running setup.py install for mozversion 08:18:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:18:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:18:47 INFO - Cleaning up... 08:18:47 INFO - Return code: 0 08:18:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:47 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 0x10a165490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8869c776d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f79d30>, '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 0x109ddfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f71b20>, '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.4bbo1fn3AR/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.mNwztwvZVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:18:47 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:18:47 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:18:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4bbo1fn3AR/Render', 08:18:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:47 INFO - 'HOME': '/Users/cltbld', 08:18:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:47 INFO - 'LOGNAME': 'cltbld', 08:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:47 INFO - 'MOZ_NO_REMOTE': '1', 08:18:47 INFO - 'NO_EM_RESTART': '1', 08:18:47 INFO - 'PAGER': '/bin/cat', 08:18:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:47 INFO - 'PWD': '/builds/slave/test', 08:18:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:47 INFO - 'SHELL': '/bin/bash', 08:18:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mNwztwvZVH/Listeners', 08:18:47 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:18:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:47 INFO - 'USER': 'cltbld', 08:18:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:47 INFO - 'XPC_FLAGS': '0x0', 08:18:47 INFO - 'XPC_SERVICE_NAME': '0', 08:18:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:18:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-MW6YOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:18:48 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:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:18:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Atw3It-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:18:48 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:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:18:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-_lBVln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:18:48 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:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:18:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Mg5hbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:18:48 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:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:18:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-fZ0pmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:18:48 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:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:18:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Nw2SWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:18:48 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:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:18:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Fo81RY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:18:48 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:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:18:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-UrktZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:18:48 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:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:18:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-voWDFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:18:49 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:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:18:49 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-TmYi0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:18:49 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:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:18:49 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-HbH3Cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:18:49 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:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:18:49 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-deG7aJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:18:49 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:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:18:49 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-cEYGdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:18:49 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:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:18:49 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-BWvMlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:18:49 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:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:18:49 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-RGGiGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:18:49 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:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:18:49 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-iy60X1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:18:49 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:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:18:49 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-8Iz3jJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:18:49 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:18:49 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:18:49 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:18:49 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:18:49 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:18:49 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:18:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:52 INFO - Downloading blessings-1.6.tar.gz 08:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:18:52 INFO - Installing collected packages: blessings 08:18:52 INFO - Running setup.py install for blessings 08:18:52 INFO - Successfully installed blessings 08:18:52 INFO - Cleaning up... 08:18:52 INFO - Return code: 0 08:18:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:18:53 INFO - Reading from file tmpfile_stdout 08:18:53 INFO - Current package versions: 08:18:53 INFO - blessings == 1.6 08:18:53 INFO - blobuploader == 1.2.4 08:18:53 INFO - docopt == 0.6.1 08:18:53 INFO - functools32 == 3.2.3-2 08:18:53 INFO - jsonschema == 2.5.1 08:18:53 INFO - manifestparser == 1.1 08:18:53 INFO - mozInstall == 1.12 08:18:53 INFO - mozcrash == 1.0 08:18:53 INFO - mozdebug == 0.1 08:18:53 INFO - mozdevice == 0.48 08:18:53 INFO - mozfile == 1.2 08:18:53 INFO - mozhttpd == 0.7 08:18:53 INFO - mozinfo == 0.9 08:18:53 INFO - mozleak == 0.1 08:18:53 INFO - mozlog == 3.4 08:18:53 INFO - moznetwork == 0.27 08:18:53 INFO - mozprocess == 0.23 08:18:53 INFO - mozprofile == 0.28 08:18:53 INFO - mozrunner == 6.13 08:18:53 INFO - mozscreenshot == 0.1 08:18:53 INFO - mozsystemmonitor == 0.3 08:18:53 INFO - moztest == 0.7 08:18:53 INFO - mozversion == 1.4 08:18:53 INFO - psutil == 3.1.1 08:18:53 INFO - requests == 1.2.3 08:18:53 INFO - wsgiref == 0.1.2 08:18:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:53 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 0x10a165490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8869c776d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f79d30>, '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 0x109ddfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f71b20>, '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.4bbo1fn3AR/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.mNwztwvZVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:18:53 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:18:53 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:18:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4bbo1fn3AR/Render', 08:18:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:53 INFO - 'HOME': '/Users/cltbld', 08:18:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:53 INFO - 'LOGNAME': 'cltbld', 08:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:53 INFO - 'MOZ_NO_REMOTE': '1', 08:18:53 INFO - 'NO_EM_RESTART': '1', 08:18:53 INFO - 'PAGER': '/bin/cat', 08:18:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:53 INFO - 'PWD': '/builds/slave/test', 08:18:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:53 INFO - 'SHELL': '/bin/bash', 08:18:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mNwztwvZVH/Listeners', 08:18:53 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:18:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:53 INFO - 'USER': 'cltbld', 08:18:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:53 INFO - 'XPC_FLAGS': '0x0', 08:18:53 INFO - 'XPC_SERVICE_NAME': '0', 08:18:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:53 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:18:53 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:18:53 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:18:53 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:18:53 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:18:53 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:18:53 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:18:53 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:18:53 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:18:53 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:18:53 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:18:53 INFO - Cleaning up... 08:18:53 INFO - Return code: 0 08:18:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a165490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8869c776d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f79d30>, '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 0x109ddfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f71b20>, '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.4bbo1fn3AR/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.mNwztwvZVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:18:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:18:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4bbo1fn3AR/Render', 08:18:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:53 INFO - 'HOME': '/Users/cltbld', 08:18:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:53 INFO - 'LOGNAME': 'cltbld', 08:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:53 INFO - 'MOZ_NO_REMOTE': '1', 08:18:53 INFO - 'NO_EM_RESTART': '1', 08:18:53 INFO - 'PAGER': '/bin/cat', 08:18:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:53 INFO - 'PWD': '/builds/slave/test', 08:18:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:53 INFO - 'SHELL': '/bin/bash', 08:18:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mNwztwvZVH/Listeners', 08:18:53 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:18:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:53 INFO - 'USER': 'cltbld', 08:18:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:53 INFO - 'XPC_FLAGS': '0x0', 08:18:53 INFO - 'XPC_SERVICE_NAME': '0', 08:18:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:18:53 INFO - Cleaning up... 08:18:53 INFO - Return code: 0 08:18:53 INFO - Running post-action listener: _resource_record_post_action 08:18:53 INFO - Running post-action listener: _start_resource_monitoring 08:18:53 INFO - Starting resource monitoring. 08:18:53 INFO - [mozharness: 2016-12-04 16:18:53.731462Z] Finished create-virtualenv step (success) 08:18:53 INFO - [mozharness: 2016-12-04 16:18:53.732425Z] Running install step. 08:18:53 INFO - Running pre-action listener: _resource_record_pre_action 08:18:53 INFO - Running main action method: install 08:18:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:18:53 INFO - Reading from file tmpfile_stdout 08:18:53 INFO - Detecting whether we're running mozinstall >=1.0... 08:18:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:18:54 INFO - Reading from file tmpfile_stdout 08:18:54 INFO - Output received: 08:18:54 INFO - Usage: mozinstall [options] installer 08:18:54 INFO - Options: 08:18:54 INFO - -h, --help show this help message and exit 08:18:54 INFO - -d DEST, --destination=DEST 08:18:54 INFO - Directory to install application into. [default: 08:18:54 INFO - "/builds/slave/test"] 08:18:54 INFO - --app=APP Application being installed. [default: firefox] 08:18:54 INFO - mkdir: /builds/slave/test/build/application 08:18:54 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:18:54 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:19:25 INFO - Reading from file tmpfile_stdout 08:19:25 INFO - Output received: 08:19:25 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:19:25 INFO - Running post-action listener: _resource_record_post_action 08:19:25 INFO - [mozharness: 2016-12-04 16:19:25.197817Z] Finished install step (success) 08:19:25 INFO - [mozharness: 2016-12-04 16:19:25.197957Z] Running run-tests step. 08:19:25 INFO - Running pre-action listener: _resource_record_pre_action 08:19:25 INFO - Running main action method: run_tests 08:19:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:19:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:19:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:19:25 INFO - 2016-12-04 08:19:25.211 screenresolution[1631:8322] starting screenresolution argv=screenresolution get 08:19:25 INFO - 2016-12-04 08:19:25.213 screenresolution[1631:8322] Display 0: 1600x1200x32@60 08:19:25 INFO - 2016-12-04 08:19:25.219 screenresolution[1632:8325] starting screenresolution argv=screenresolution list 08:19:25 INFO - Available Modes on Display 0 08:19:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:19:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:19:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:19:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:19:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:19:25 INFO - Intel Iris: 08:19:25 INFO - Chipset Model: Intel Iris 08:19:25 INFO - Type: GPU 08:19:25 INFO - Bus: Built-In 08:19:25 INFO - VRAM (Dynamic, Max): 1536 MB 08:19:25 INFO - Vendor: Intel (0x8086) 08:19:25 INFO - Device ID: 0x0a2e 08:19:25 INFO - Revision ID: 0x0009 08:19:25 INFO - Displays: 08:19:25 INFO - Display: 08:19:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:19:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:19:25 INFO - Display Serial Number: 1600x1200 60 08:19:25 INFO - Main Display: Yes 08:19:25 INFO - Mirror: Off 08:19:25 INFO - Online: Yes 08:19:25 INFO - Rotation: Supported 08:19:25 INFO - Return code: 0 08:19:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:19:25 INFO - Python 2.7.3 08:19:25 INFO - Return code: 0 08:19:25 INFO - grabbing minidump binary from tooltool 08:19: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:19:25 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 0x109f79d30>, '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 0x109ddfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f71b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:19:25 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:19:25 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:19:25 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:19:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:19:25 INFO - Return code: 0 08:19:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:19:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:19:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:19:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:19:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:19:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0077 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:19:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4bbo1fn3AR/Render', 08:19:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:19:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:19:25 INFO - 'HOME': '/Users/cltbld', 08:19:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:19:25 INFO - 'LOGNAME': 'cltbld', 08:19:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:19:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:19:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:25 INFO - 'MOZ_NO_REMOTE': '1', 08:19:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:19:25 INFO - 'NO_EM_RESTART': '1', 08:19:25 INFO - 'PAGER': '/bin/cat', 08:19:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:19:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:25 INFO - 'PWD': '/builds/slave/test', 08:19:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:19:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:19:25 INFO - 'SHELL': '/bin/bash', 08:19:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mNwztwvZVH/Listeners', 08:19:25 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:19:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:19:25 INFO - 'USER': 'cltbld', 08:19:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:19:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:19:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:25 INFO - 'XPC_FLAGS': '0x0', 08:19:25 INFO - 'XPC_SERVICE_NAME': '0', 08:19:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:19:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:19:25 INFO - mozversion application_buildid: 20161204063138 08:19:25 INFO - mozversion application_changeset: d10b8f6cce8d37787855c210e9b9f8110ceb45d1 08:19:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:19:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:19:25 INFO - mozversion application_name: Firefox 08:19:25 INFO - mozversion application_remotingname: firefox-dev 08:19:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:19:25 INFO - mozversion application_vendor: Mozilla 08:19:25 INFO - mozversion application_version: 52.0a2 08:19:25 INFO - mozversion platform_buildid: 20161204063138 08:19:25 INFO - mozversion platform_changeset: d10b8f6cce8d37787855c210e9b9f8110ceb45d1 08:19:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:19:25 INFO - mozversion platform_version: 52.0a2 08:19:25 INFO - using testdate: 1480868365 08:19:25 INFO - actual date: 1480868365 08:19:25 INFO - starting webserver on 'localhost:49241' 08:19:25 INFO - SUITE-START | Running 2 tests 08:19:25 INFO - TEST-START | dromaeo_css 08:19:25 INFO - operating with platform_type : mac_ 08:19:25 INFO - Initialising browser for dromaeo_css test... 08:19:25 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmp7A2tCz/profile http://localhost:49241/getInfo.html) 08:19:28 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 08:19:28 INFO - PROCESS | 1642 | colorDepth:24 08:19:28 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 08:19:28 INFO - PROCESS | 1642 | __metrics 08:19:28 INFO - PROCESS | 1642 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:19:28 INFO - PROCESS | 1642 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:19:29 INFO - TEST-INFO | 1642: exit 0 08:19:29 INFO - Browser initialized. 08:19:29 INFO - Running cycle 1/1 for dromaeo_css test... 08:19:29 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmp7A2tCz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:19:41 INFO - PROCESS | 1645 | RSS: Main: 222273536 08:19:41 INFO - PROCESS | 1645 | 08:20:32 INFO - PROCESS | 1645 | 1480868432498 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:22:47 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 08:22:49 INFO - PROCESS | 1645 | RSS: Main: 418660352 08:22:49 INFO - PROCESS | 1645 | 08:25:52 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 08:25:53 INFO - PROCESS | 1645 | RSS: Main: 346120192 08:25:53 INFO - PROCESS | 1645 | 08:28:55 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 08:28:56 INFO - PROCESS | 1645 | RSS: Main: 323837952 08:28:56 INFO - PROCESS | 1645 | 08:31:58 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 08:31:59 INFO - PROCESS | 1645 | RSS: Main: 318386176 08:31:59 INFO - PROCESS | 1645 | 08:35:02 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 08:35:03 INFO - PROCESS | 1645 | RSS: Main: 304447488 08:35:03 INFO - PROCESS | 1645 | 08:38:05 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 08:38:06 INFO - PROCESS | 1645 | RSS: Main: 376590336 08:38:06 INFO - PROCESS | 1645 | 08:38:06 INFO - PROCESS | 1645 | __start_tp_report 08:38:06 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 08:38:06 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 08:38:06 INFO - PROCESS | 1645 | |i|pagename|runs| 08:38:06 INFO - PROCESS | 1645 | |0;dojo.html;3352.497125431186;3352.748543859211;3363.276895467477;3367.915802104947;3420.2988387380587;5736.770529178828;5765.740541675625;5774.6246493977915;5791.276090488689;5859.589828711986;5739.339975902762;5806.506446952004;5824.650520968745;5832.9708351458185;5835.241418615574;5233.476652334764;5252.3171987641745;5260.947390526084;5286.2070689396705;5301.363836339645;5222.477752224785;5241.082810508165;5247;5354.785808567672;5359.5444387227135;5272.31459910213;5307.283516725251;5311.203319502067;5313.3092697949405;5316.601254905878;5312.468753124676;5326.348831862116;5337.386200586904;5337.786488540453;5347.973260133714;5211.5830733541225;5245.055889939809;5260.184671375943;5268.657537260104;5276.2085687146855;5847.239858818326;5851.765929362863;5852.209951656514;5857.297124345063;5862.912056319158;5141.897162056738;6159.7536098556;6184.19605451293;6186.072089186613;6206.193194884681;6272;6317.178766760338;6332.620042797447;6332.841678958017;6348.0477928310665;5392.299001129867;5796.681182534959;5858.091995740667;5862.179294898718;5907.822765317047;6283.214598175228;6301.8109456716375;6307.053941908659;6332.39842214998;6346.238451385863;6068.878622427526;6100.542459315569;6135.489990514684;6145.539084568675;6182.1345011698395;6290.370962903765;6298.779542715983;6339.239291284983;6343.904841427382;6363;5784.797532086357;6303.842403939938;6332.936670633235;6335.493160547145;6342.968285158545;5611.8597035073635;6186.072089186658;6228.750849966042;6251.562390632703;6281.528885333618;4941.6293777966075;4942.085714142895;4947.356843610308;4963.726995015309;4983.028309479617;3811;4458.955410445854;4472.686911916199;4474.194644963938;4503.729776213438;5765.769369225268;5774.509166720792;5827.88344233113;5903.232579764689;5956.672383018976;6341.619502829845;6372.076048972873;6373.458256048195;6376.171097757262;6381.649009304533;5760.798372057042;5810.593258471951;5924.80363621862;5939.138824870456;5991.610545314541;5317.255584218213;5330.173823057432;5388.757505912244;5441.483059109379;5458.044842152607;5363.2759577456545;6257.061440783918;6267.592606480564;6300.653464059521;6344.68276586178;6358.618482891041;6362.077498762561;6370.649614271074;6381.8723625528155;6385.3295403983775;6206.503479721521;6322.33615470369;6327.968360158354;6330.8733825322315;6341.239051313872;5914.911276330945;6103.542234332354;6142.191841058572;6178;6262.029385445263;6317.4630156437215;6360.840978975562;6361.2048493938255;6362.745490180341;6363;6114.724837382418;6201.162843016135;6203.534734894991;6217.533684973555;6234.657093859882;6031.276246850406;6096.817095487142;6121.571489995836;6205.782797602243;6209.565330426827;5997.460228579449;6045.395460453919;6063.72713227897;6079.635221886701;6101.938980610137;5994.970025149846;6065.636061836304;6090.269167699905;6186.041163619646;6188.535859810442;5898.233229680286;5905.763769449174;5915.260592425947;5927.851803704942;5946.851328716817 08:38:06 INFO - PROCESS | 1645 | |1;ext.html;448.22233425007585;477.1006652460112;521.849322244451;530.5516838271661;533.7430351522167;38150.42774358383;39381.212375752504;39617.20765584687;39849.4022589661;41427;40345.19309613806;40701.18597628046;40742;40797.18405631885;40998.18003639925;8411.747647570583;8610.182032706884;9298.907010929906;9498.620055197784;9505.66730164441;11102.222844400854;11403.258788178742;11406.828897566502;11549.498565186555;11569.768604627903;11325.263857849255;11362.147838912073;11385.601503947364;11396;11399.48702308398;9979.151772099307;11080.448737176801;11317.547298108066;11374.488148033359;11385.601503947322;11149.832752508719;11203.551857925673;11221.541199644076;11231.045361144312;11250.54997800087;38266.80866595677;38591.80704096489;38649.22701545953;38666.293359464566;38675.41986870171;3253.658365871573;3461.152017755641;3645.471406646048;3799.8290076946605;3838.05967537955;26171.084012059488;26939.865300673373;27505.762240699216;28899.71100288991;28966.71033289661;2718.714534973839;3171.20719820045;3198.2803869129493;3269.8855540055984;3300.6864347886913;498581;500299;500559;502689;511366;55009.449905501235;55327;55364;55413.89172216533;55708;57624;57805.710971444874;58061.70969145127;58124;58226;15596.376144954302;15807.920960395124;15808.60478488047;15814.209289535707;15819.446319378996;10977.231593788358;11286.717832054264;11289.548418063185;11993.460294286779;12213.145079844326;14417.351219195161;14529.78205326921;14592.124472531683;14614.853851461561;14807.629809254639;5763.7982670606325;6790.524663273523;6884.896726549106;6916.688749006306;6921.204061532988;848.898132224137;950.4724877692947;976.1117383181413;1055.9313644613076;1093.4642025407493;27985.72014279872;28765.56851647227;29064.982725604503;29812.701872980993;29926.55110173349;1036.383351905615;1039.8440233964814;1048.743057950807;1155.7284038251005;1169.9002937238968;12256.81614775779;14615.78076328856;15055.774163387558;15064.472743453927;15509.302081406138;636490;638868;640551;640964;644243;55477;55508.44491555113;55510.612234694454;55559.44440555543;55580;1238.8141778733261;1272.3638180909545;1355.1191725378583;1381.081580748811;1404.9929750351182;29202.269943251587;29873.253168670955;29883;29993.55009674813;30559;42626.78686606547;43004;43075.70772876819;43120.56879431166;43160.568394315655;528284;530257;531724;533793;533796;24777.50444991054;25690.35774105662;25785.226443206735;26287.4742505145;26346.73653263443;43641.12717745564;43668.56331436645;44113;44532.77733611311;44859.327110094026;36398.636013640586;36489.63510364862;37060.25879482449;38378.808105959295;38400.2319953605;36471.27057458889;36620.63379366172;37182.44226336553;37275;38236;35842.28315433625;36338;36360.63639363572;36380.36288367083;37037.629623704495;31277.218069548442;33315.66684333126;33387.83306083454;33405;33527.329453410304;24332.391690207885;24495;24499.510009800062;24868.12961546337;24939.251822445356 08:38:06 INFO - PROCESS | 1645 | |2;jquery.html;347.461434776097;348.5817019576508;352.4273056283539;372.6925286638522;382.2126419575674;12307.446164922589;15701.92149039256;16852.49442516725;20185;20318.69521957168;18607.627847443044;19626.705599415985;19654.90172549139;19658;19692.01539923005;4069.3285607874664;5493.2034854946005;5754.02181629122;5755.6258843175265;5773.884522309551;4226.239276930151;6099.420555047263;6331.810045698637;6440.420362167404;6445.484361251112;6170.8087846796425;6276;6360.809175724736;6377.298497165308;6405.743770249184;6328.873422531547;6360.077788720656;6363.300036995926;6363.713632886531;6377.617342959391;5311.176767601028;5485.396606373296;5541.972290138564;5548.91676624851;5570.888582228333;24095.036198552036;24703.876480617662;24775.628365574532;24887.128950486647;25143.37141571457;2260.2202240227093;2300.8946813665475;2374.3470545599926;2561.7950563954837;2754.6556680414947;14891.925540372229;14979.176145312003;14980.47568335114;15671.843281567153;15855.762163567555;1725.6376161006203;1839.8804077734903;1855.7030875059925;1859.8605104617209;1861.655593715167;86329;102962.48518757358;103313.4834325831;104302.47848760783;104508.43237351446;8969.372143949991;10466.110380617733;10648.946755266328;11049.226554141133;11221.158413119048;13895.235762032982;13907.513237036857;13941.442342306194;13943.860561394458;13971.371288292052;8312;8555.187257210555;8643.222110010129;8692.521911294813;8704;8562.657493700182;8565.944173398464;8575.756515305246;8702.956485217534;8727.516322225238;5863.560232982543;5987.9221740087205;6551.443127334134;6557.506684524963;6580.86838263232;1836.6877630802796;1847.2057015483203;1862.2178684952082;1871.279557370395;1888.310766570192;723.4501778648265;743.2678811370796;847.9872801907976;852.5651917521984;923.8290916180528;17789.288428462718;20307.28924487636;20878.791212087686;21582.676259856114;21659.35021949344;934.9766255843522;951.3007939164709;954.4082668745283;983.6114734679904;1017.9949100254452;10787.029167374974;11303.265287756269;11319.830202546967;11334.60328888485;11338.60314888975;578140;581609;584459;589869;597994;67868.6606566974;122006;123115.38442307731;123291;124537;942.3168203052869;956.3831328793028;981.8134554434778;983.0611765763679;983.5672304186136;18758.906205468884;19155.808441915404;19249.807501925363;19267.421977340702;19286.517837054187;52340.47659523356;52585.7370713144;53399.733001334745;53768;54671.906561869335;54158.042615313076;72178.55642887008;75435.24564754282;75459.24540754742;75763.62118189594;7521.548707077593;7852.528848269122;8115.715949941724;8139.715109971123;8284.46151000183;11870.703232419259;12895.41970611306;12930;13276.933615331862;13344.132631378929;11068.225224234548;11259.268147570381;11430.371329576954;11696.532138714356;11726.70683232926;11043.889561104286;11231.38227397501;11344.886551134714;11612.361320127475;11756.882431175922;11497.252678575866;11534.423278836193;11650.592229271935;11960.521579136737;11978.281303121841;7971.402144839044;7975.481593696391;8228;8362.874556881774;8374.785656079712;11202;11239.269447485887;11352.659420217407;11556.653300401002;11606.419679016184 08:38:06 INFO - PROCESS | 1645 | |3;mootools.html;1434.3545404567944;1473.9852601473985;1576.5900865774895;1775.547235454958;1782.224732241476;8031.237032481919;8451;8462.449940753846;8477.872831907527;8481.406301558893;7375.225601311866;7609.92390076099;7618.466707330489;7914.129445760962;8272.090070092285;1962.6565351063578;2268.7731226877263;2306.573283942476;2316.9652455213186;2319.93040208794;2295.2769877488536;2331.207389487574;2345.566070277004;2347.8943447544816;2351.1653363056125;1890.922174360615;1951.677973134431;2301.0335659023167;2321.767823217673;2326.081197926818;2270.761570035147;2293.0827668932393;2294.380517260347;2299.9425014374774;2301.390131615115;2283.8058765004994;2325.7092863392077;2327.592671282519;2330.8951097200666;2331.976680233193;8248.958755206246;8289.378296627716;8332.62503187352;8362.790930226733;8389.203025712548;5060.367454068241;5187.922181167285;5212.6611770235195;5217.113090774577;5218.347706536683;3017.788754787166;3244.7890887092262;3450.102973226954;3508.4737289406794;3518.9648103518894;937.864009718587;1006.808706345792;1050.144132531984;1082.7293176705823;1088.2708585247824;10755.247132700713;10881.945590271998;10901;10908.072813810835;10917.945410272898;4564.406627138451;4726.71639701619;4752.5485078917445;4767.094252092161;4780.25905984566;8667.349948753868;8710.477371357612;8730.69442569507;8738.432001919855;8759.649614015369;5086.21163719624;5105.387353517527;5113.386393632788;5113.846584602468;5119;4595.356650068993;4754.453237877619;4797.18447863864;4823.5658790709;4861.100696371112;3598.9280214395567;3667.2298817248675;3692.2061756722433;3736.831842567091;3754.4743735877;1137.863456385239;1139.5954436175205;1149.0290704354807;1150.649052039136;1157.0743405275746;761.919998400171;766.9156392796788;845.0999685335221;873.3100850328296;885.9103302937423;5405.081136206854;5452.509274165265;5563.499285064364;5618.129189975561;5622.437756224426;352.3288136100732;428.9335153051282;433.8763452416109;436.2997389190367;444.034225559407;3719.628037196259;3779.055236190952;3799;3812.1803812180515;3906.8046597670004;9672.70981870545;10170.491475426199;10190.592376304865;10192.694219173436;10241.436720980417;10088.949555252177;10103.696889093339;10134.493275336205;10159.085682288629;10170.08469237772;7634.6182690865235;7739;7763.146053934062;7922.0889597697005;7949.920500795034;5374.3013408256675;5459;5491.148871924858;5520.033994051106;5735.082386818089;8231.835363292821;9264.768380790534;9299.535023248674;9394.201492873182;9421.858672119786;6911.481638877205;8768.78078048054;8830.823383532164;9515.476648784383;9626.66306679263;6611.206655201407;6771.085903402977;6775.254721980479;6775.661216939232;6785.558938668971;7569.886451703339;7722.53664780115;7839.137694853675;7844.960775196316;7850.018747656543;7009.474289428415;7047.40097091753;7114.679839407136;7212.639368031472;7289.963550182215;6937.7571784987285;7030.648467576703;7037.577745335297;7049.753258635923;7058.400035996792;6568.474522038321;6576.506761992774;6602.273749887605;6606.735730570724;6684.799456016327;4668.976655116703;4765.76171191446;4828.130936431476;4830.903381932412;4847.75761211931;4772.117158325721;4772.570468657837;4853.320535125014;4913.950860491493;5056.924146137737 08:38:06 INFO - PROCESS | 1645 | |4;prototype.html;303.41138191907703;307.9892203772867;312.7826160818231;318.6733598061986;322.5484321949272;4790.664653474259;5248.6063545234065;5439.891202175954;5522.3649280332775;5539.086050801618;5101.7755738622745;5242.135047717132;5378.542823859967;5406.89158722462;5472;3376.3416133853907;3470.171809974875;3914.628110329528;4179.874603761892;4188.937165942498;3308.7518436117266;3704.4258139988347;4188.078622703016;4193.706440549163;4219.683523735732;4129.483814523184;4162.43807086044;4170.332746760519;4175.227582897159;4206.978965105185;3813.866514671973;3883.825227864753;4125.8751742852455;4161.191195942742;4175.54069052404;3406.9608769325346;3542.1321776164746;3560.2345495718287;3622.728295377857;3626.202235508184;4207.537170911197;5343.492368225013;5347.946520534783;5358.705271210082;5359.437259087778;4556.746894603977;4695.835645752416;4705.458872229703;4723.220668589679;4729.533844508213;5427.972860135674;5681;5734.455226753452;5744.22452968848;5745.597808153389;3315.535824984504;3917.2372432405245;3917.9216415671526;3923;3927.9410808837893;5967.701614919237;6008.939910600881;6017.7592896284095;6038.728257228391;6054.3945605439985;3776.50905382304;3936.2717897189114;3962.5046869141356;3985.3822657488136;4009.7794621296107;3363.327334533103;3443.8648038215965;3540.2919416116365;3720.720945929066;4034.3948407739076;4155.792210389468;4388.517263101056;4689.62483001359;4702.224133018082;4711.869151403638;4587.72473651582;5438.91841622376;5548.1427065061525;5703.201551782754;5746.655200687972;1223.2843786384888;1241.8199361092773;1247.65689435406;1257.6415721519322;1269.631806776043;810.1898101898101;866.7562048460479;877.087828658201;879.7668617816283;882.2236431939851;4615.446146462379;4712.293156026555;5013.89972200554;5256.290400795843;5280.577553795687;4773.04539092176;5089.389273287229;5097.362829646294;5236.895262094813;5666.235058267163;4031.7580945143386;4231.61915427613;4873.610111191168;5020.171671674133;5163.4062082860955;2614.535859918452;2873.5976963225166;2881.3228891210547;2901.2746813296676;2910.4138244656715;4979.6265280104135;5314.5748340132695;5439.211314359396;5667.914981275284;5748.741306641211;4809.326694262806;4870.076319853638;5066.265391518209;5210.7915683373;5470.699111548903;5225.216217567396;5304.336957880265;5673.290838645169;5709.972205002973;5731.512821410227;5001.899962000813;5150.381954165524;5471.759386475514;5542.473465020817;5563.527100196528;4708.741019243975;4713.2458806590785;5201.323827902348;5453.15476101205;5455.917406665221;4874.854409213903;5185.559227465556;5659.377468478547;5774;5803.506701930383;3025.4100450411956;3988.0628052407083;4010.2981978153125;4010.7593544387432;4039.646718349386;5774.3648198697265;5860.619059761102;5950.2810743285445;5986.760529578768;6034.39656034393;4935.383077115361;5072.188449848007;5179.249008893614;5536.698875764112;5859.384764599656;5733.04419950233;5752.964466396089;5757.625754325955;5778.422157784188;5782.207515670156;5369.972520609543;5435.83692489226;5573.7213139343685;5575.968445837533;5666.600039975981;4957.231629097496;5745.224394706661;5773.278340207474;5775.768969241183;5816.331121921034;4928.556429921325;5097.643164978416;5289.894202116013;5613.410591887793;5626.915596266142 08:38:06 INFO - PROCESS | 1645 | |5;yui.html;642.8296501427121;741.8775901976175;775.6897241103561;784.3098073695147;805.8428606421749;13744.381502832372;13943.442262309509;14279.286035698226;14446.927765361184;14647.92676036621;12834.743305133916;13031.543895963643;13158.473661053546;13164.210147391163;13171.73656526869;778.1906816910406;779.5790273252451;809.3080416244114;809.8987626546682;811.119934870666;761.6458346868733;765.8276887700279;767.0411985018727;776.6000509737489;801.7274126796888;846.8137009857821;847;848.2323497234981;848.8811566380681;851.5997481183833;839.5382539603194;839.9286060684849;841.4530555139147;842.9620667069996;843.3927572148043;620.9782657607008;622.3993845938691;624.1636207481996;625.2684359299585;627.0594108836744;12040.638780836589;12363.938180309131;12395.62813115608;12408.689782755413;12410.937945310307;11636.243644163102;11674.066074714088;11832.763344733143;11907.940460297643;11942.761144777056;10265.794684106351;10394.688159355232;10462.947685261524;10545.9472702636;10590.629327973562;3414.590249170091;3494.790312581253;3607.2424790793966;3662.1943172502006;3743.9625603743884;81930.09201555302;85258.57370713107;87508;87538.5623071887;87657.12342876554;8604.268637165911;8644.870326945102;8722.595089479899;8848.46909185451;8980.506072166094;18384.724229136573;18549.90725046366;18619.789713668568;18635.347762828234;18738.81261187398;7228.590424867206;7574.848503030019;7607.733729319447;7787.5716835574585;7789.143194248628;3999.3800960851113;4025.6430676116415;4069.654079403284;4070.1859628074503;4088.8160032798596;3308.71875890552;3388.5594872666397;3455.274392377579;3481.2863363010524;3487.163080860575;609.2354095609982;614.5882258886556;627.9058141278754;644.8033349828254;655.3381085104079;628.2523796681934;636.1507387637463;642.5437939063318;653.5849735418044;668.8327918020495;8438.915610843937;9210.03294654065;9392.21565834174;9394.530273486298;9457.621695132118;575.0942265931175;589.8672798620362;620.7610070122945;640.0143778580933;691.6991108867659;4076.8369265229057;4468.664850136252;4506.233940230169;4552.818056231914;4565.56627120423;104341.95658043474;109177;113128;119018;123671.5265694699;60043.85527758467;65413;67551.66224168945;70457.6477117611;85416.14583854206;68312.65843670849;80240;81676;85804;87030.56484717662;9188.862167067633;9734.221262298857;9769.316147869866;9776.462294573867;9785.608575656894;38438;53464.93070138653;55358.89282214252;55632;56045;73788;78070;81361;82322.17677823384;82770;11791.76416471683;12010.279383237033;12095.39523023863;12373.75252494963;12664.74670506603;13573.981951353886;13649.658758531115;13964.720705586034;14127.717445651235;14128.43486260538;12669.429875655433;12743.93628031891;12877.742445150858;12881.098323117667;13018.28399438002;12534;12821.615351539469;12864.292463914575;12919.22484650924;13557.661058473617;12662.366881656064;12713.745725085631;12846.229226246454;12890.849415255923;12918.806217906551;10260.281780275309;10325.173986081245;10604.310719803188;11024.44877756125;11340.6030788922;10572.259941803999;11000.889991100306;11225.270357426742;11246.831297530707;11297 08:38:06 INFO - PROCESS | 1645 | __end_tp_report 08:38:06 INFO - PROCESS | 1645 | __start_cc_report 08:38:06 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,2508 08:38:06 INFO - PROCESS | 1645 | __end_cc_report 08:38:06 INFO - PROCESS | 1645 | __startTimestamp1480869486172__endTimestamp 08:38:06 INFO - PROCESS | 1645 | 08:38:06 INFO - PROCESS | 1645 | ------- Summary: start ------- 08:38:06 INFO - PROCESS | 1645 | Number of tests: 6 08:38:06 INFO - PROCESS | 1645 | 08:38:06 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5784.00 Median:5946.85 stddev:650.59 (10.9%) stddev-sans-first:624.00 08:38:06 INFO - PROCESS | 1645 | Values: 3352.5 3352.7 3363.3 3367.9 3420.3 5736.8 5765.7 5774.6 5791.3 5859.6 5739.3 5806.5 5824.7 5833.0 5835.2 5233.5 5252.3 5260.9 5286.2 5301.4 5222.5 5241.1 5247.0 5354.8 5359.5 5272.3 5307.3 5311.2 5313.3 5316.6 5312.5 5326.3 5337.4 5337.8 5348.0 5211.6 5245.1 5260.2 5268.7 5276.2 5847.2 5851.8 5852.2 5857.3 5862.9 5141.9 6159.8 6184.2 6186.1 6206.2 6272.0 6317.2 6332.6 6332.8 6348.0 5392.3 5796.7 5858.1 5862.2 5907.8 6283.2 6301.8 6307.1 6332.4 6346.2 6068.9 6100.5 6135.5 6145.5 6182.1 6290.4 6298.8 6339.2 6343.9 6363.0 5784.8 6303.8 6332.9 6335.5 6343.0 5611.9 6186.1 6228.8 6251.6 6281.5 4941.6 4942.1 4947.4 4963.7 4983.0 3811.0 4459.0 4472.7 4474.2 4503.7 5765.8 5774.5 5827.9 5903.2 5956.7 6341.6 6372.1 6373.5 6376.2 6381.6 5760.8 5810.6 5924.8 5939.1 5991.6 5317.3 5330.2 5388.8 5441.5 5458.0 5363.3 6257.1 6267.6 6300.7 6344.7 6358.6 6362.1 6370.6 6381.9 6385.3 6206.5 6322.3 6328.0 6330.9 6341.2 5914.9 6103.5 6142.2 6178.0 6262.0 6317.5 6360.8 6361.2 6362.7 6363.0 6114.7 6201.2 6203.5 6217.5 6234.7 6031.3 6096.8 6121.6 6205.8 6209.6 5997.5 6045.4 6063.7 6079.6 6101.9 5995.0 6065.6 6090.3 6186.0 6188.5 5898.2 5905.8 5915.3 5927.9 5946.9 08:38:06 INFO - PROCESS | 1645 | 08:38:06 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:68285.24 Median:36370.50 stddev:150027.62 (412.5%) stddev-sans-first:150362.03 08:38:06 INFO - PROCESS | 1645 | Values: 448.2 477.1 521.8 530.6 533.7 38150.4 39381.2 39617.2 39849.4 41427.0 40345.2 40701.2 40742.0 40797.2 40998.2 8411.7 8610.2 9298.9 9498.6 9505.7 11102.2 11403.3 11406.8 11549.5 11569.8 11325.3 11362.1 11385.6 11396.0 11399.5 9979.2 11080.4 11317.5 11374.5 11385.6 11149.8 11203.6 11221.5 11231.0 11250.5 38266.8 38591.8 38649.2 38666.3 38675.4 3253.7 3461.2 3645.5 3799.8 3838.1 26171.1 26939.9 27505.8 28899.7 28966.7 2718.7 3171.2 3198.3 3269.9 3300.7 498581.0 500299.0 500559.0 502689.0 511366.0 55009.4 55327.0 55364.0 55413.9 55708.0 57624.0 57805.7 58061.7 58124.0 58226.0 15596.4 15807.9 15808.6 15814.2 15819.4 10977.2 11286.7 11289.5 11993.5 12213.1 14417.4 14529.8 14592.1 14614.9 14807.6 5763.8 6790.5 6884.9 6916.7 6921.2 848.9 950.5 976.1 1055.9 1093.5 27985.7 28765.6 29065.0 29812.7 29926.6 1036.4 1039.8 1048.7 1155.7 1169.9 12256.8 14615.8 15055.8 15064.5 15509.3 636490.0 638868.0 640551.0 640964.0 644243.0 55477.0 55508.4 55510.6 55559.4 55580.0 1238.8 1272.4 1355.1 1381.1 1405.0 29202.3 29873.3 29883.0 29993.6 30559.0 42626.8 43004.0 43075.7 43120.6 43160.6 528284.0 530257.0 531724.0 533793.0 533796.0 24777.5 25690.4 25785.2 26287.5 26346.7 43641.1 43668.6 44113.0 44532.8 44859.3 36398.6 36489.6 37060.3 38378.8 38400.2 36471.3 36620.6 37182.4 37275.0 38236.0 35842.3 36338.0 36360.6 36380.4 37037.6 31277.2 33315.7 33387.8 33405.0 33527.3 24332.4 24495.0 24499.5 24868.1 24939.3 08:38:06 INFO - PROCESS | 1645 | 08:38:06 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:33659.33 Median:13852.58 stddev:97190.41 (701.6%) stddev-sans-first:97430.87 08:38:06 INFO - PROCESS | 1645 | Values: 347.5 348.6 352.4 372.7 382.2 12307.4 15701.9 16852.5 20185.0 20318.7 18607.6 19626.7 19654.9 19658.0 19692.0 4069.3 5493.2 5754.0 5755.6 5773.9 4226.2 6099.4 6331.8 6440.4 6445.5 6170.8 6276.0 6360.8 6377.3 6405.7 6328.9 6360.1 6363.3 6363.7 6377.6 5311.2 5485.4 5542.0 5548.9 5570.9 24095.0 24703.9 24775.6 24887.1 25143.4 2260.2 2300.9 2374.3 2561.8 2754.7 14891.9 14979.2 14980.5 15671.8 15855.8 1725.6 1839.9 1855.7 1859.9 1861.7 86329.0 102962.5 103313.5 104302.5 104508.4 8969.4 10466.1 10648.9 11049.2 11221.2 13895.2 13907.5 13941.4 13943.9 13971.4 8312.0 8555.2 8643.2 8692.5 8704.0 8562.7 8565.9 8575.8 8703.0 8727.5 5863.6 5987.9 6551.4 6557.5 6580.9 1836.7 1847.2 1862.2 1871.3 1888.3 723.5 743.3 848.0 852.6 923.8 17789.3 20307.3 20878.8 21582.7 21659.4 935.0 951.3 954.4 983.6 1018.0 10787.0 11303.3 11319.8 11334.6 11338.6 578140.0 581609.0 584459.0 589869.0 597994.0 67868.7 122006.0 123115.4 123291.0 124537.0 942.3 956.4 981.8 983.1 983.6 18758.9 19155.8 19249.8 19267.4 19286.5 52340.5 52585.7 53399.7 53768.0 54671.9 54158.0 72178.6 75435.2 75459.2 75763.6 7521.5 7852.5 8115.7 8139.7 8284.5 11870.7 12895.4 12930.0 13276.9 13344.1 11068.2 11259.3 11430.4 11696.5 11726.7 11043.9 11231.4 11344.9 11612.4 11756.9 11497.3 11534.4 11650.6 11960.5 11978.3 7971.4 7975.5 8228.0 8362.9 8374.8 11202.0 11239.3 11352.7 11556.7 11606.4 08:38:06 INFO - PROCESS | 1645 | 08:38:06 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5358.57 Median:4887.53 stddev:2955.97 (60.5%) stddev-sans-first:2949.55 08:38:06 INFO - PROCESS | 1645 | Values: 1434.4 1474.0 1576.6 1775.5 1782.2 8031.2 8451.0 8462.4 8477.9 8481.4 7375.2 7609.9 7618.5 7914.1 8272.1 1962.7 2268.8 2306.6 2317.0 2319.9 2295.3 2331.2 2345.6 2347.9 2351.2 1890.9 1951.7 2301.0 2321.8 2326.1 2270.8 2293.1 2294.4 2299.9 2301.4 2283.8 2325.7 2327.6 2330.9 2332.0 8249.0 8289.4 8332.6 8362.8 8389.2 5060.4 5187.9 5212.7 5217.1 5218.3 3017.8 3244.8 3450.1 3508.5 3519.0 937.9 1006.8 1050.1 1082.7 1088.3 10755.2 10881.9 10901.0 10908.1 10917.9 4564.4 4726.7 4752.5 4767.1 4780.3 8667.3 8710.5 8730.7 8738.4 8759.6 5086.2 5105.4 5113.4 5113.8 5119.0 4595.4 4754.5 4797.2 4823.6 4861.1 3598.9 3667.2 3692.2 3736.8 3754.5 1137.9 1139.6 1149.0 1150.6 1157.1 761.9 766.9 845.1 873.3 885.9 5405.1 5452.5 5563.5 5618.1 5622.4 352.3 428.9 433.9 436.3 444.0 3719.6 3779.1 3799.0 3812.2 3906.8 9672.7 10170.5 10190.6 10192.7 10241.4 10088.9 10103.7 10134.5 10159.1 10170.1 7634.6 7739.0 7763.1 7922.1 7949.9 5374.3 5459.0 5491.1 5520.0 5735.1 8231.8 9264.8 9299.5 9394.2 9421.9 6911.5 8768.8 8830.8 9515.5 9626.7 6611.2 6771.1 6775.3 6775.7 6785.6 7569.9 7722.5 7839.1 7845.0 7850.0 7009.5 7047.4 7114.7 7212.6 7290.0 6937.8 7030.6 7037.6 7049.8 7058.4 6568.5 6576.5 6602.3 6606.7 6684.8 4669.0 4765.8 4828.1 4830.9 4847.8 4772.1 4772.6 4853.3 4914.0 5056.9 08:38:06 INFO - PROCESS | 1645 | 08:38:06 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4505.59 Median:5043.22 stddev:1387.11 (27.5%) stddev-sans-first:1354.67 08:38:06 INFO - PROCESS | 1645 | Values: 303.4 308.0 312.8 318.7 322.5 4790.7 5248.6 5439.9 5522.4 5539.1 5101.8 5242.1 5378.5 5406.9 5472.0 3376.3 3470.2 3914.6 4179.9 4188.9 3308.8 3704.4 4188.1 4193.7 4219.7 4129.5 4162.4 4170.3 4175.2 4207.0 3813.9 3883.8 4125.9 4161.2 4175.5 3407.0 3542.1 3560.2 3622.7 3626.2 4207.5 5343.5 5347.9 5358.7 5359.4 4556.7 4695.8 4705.5 4723.2 4729.5 5428.0 5681.0 5734.5 5744.2 5745.6 3315.5 3917.2 3917.9 3923.0 3927.9 5967.7 6008.9 6017.8 6038.7 6054.4 3776.5 3936.3 3962.5 3985.4 4009.8 3363.3 3443.9 3540.3 3720.7 4034.4 4155.8 4388.5 4689.6 4702.2 4711.9 4587.7 5438.9 5548.1 5703.2 5746.7 1223.3 1241.8 1247.7 1257.6 1269.6 810.2 866.8 877.1 879.8 882.2 4615.4 4712.3 5013.9 5256.3 5280.6 4773.0 5089.4 5097.4 5236.9 5666.2 4031.8 4231.6 4873.6 5020.2 5163.4 2614.5 2873.6 2881.3 2901.3 2910.4 4979.6 5314.6 5439.2 5667.9 5748.7 4809.3 4870.1 5066.3 5210.8 5470.7 5225.2 5304.3 5673.3 5710.0 5731.5 5001.9 5150.4 5471.8 5542.5 5563.5 4708.7 4713.2 5201.3 5453.2 5455.9 4874.9 5185.6 5659.4 5774.0 5803.5 3025.4 3988.1 4010.3 4010.8 4039.6 5774.4 5860.6 5950.3 5986.8 6034.4 4935.4 5072.2 5179.2 5536.7 5859.4 5733.0 5753.0 5757.6 5778.4 5782.2 5370.0 5435.8 5573.7 5576.0 5666.6 4957.2 5745.2 5773.3 5775.8 5816.3 4928.6 5097.6 5289.9 5613.4 5626.9 08:38:06 INFO - PROCESS | 1645 | 08:38:06 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:19605.17 Median:29015.25 stddev:28809.60 (99.3%) stddev-sans-first:28855.23 08:38:06 INFO - PROCESS | 1645 | Values: 642.8 741.9 775.7 784.3 805.8 13744.4 13943.4 14279.3 14446.9 14647.9 12834.7 13031.5 13158.5 13164.2 13171.7 778.2 779.6 809.3 809.9 811.1 761.6 765.8 767.0 776.6 801.7 846.8 847.0 848.2 848.9 851.6 839.5 839.9 841.5 843.0 843.4 621.0 622.4 624.2 625.3 627.1 12040.6 12363.9 12395.6 12408.7 12410.9 11636.2 11674.1 11832.8 11907.9 11942.8 10265.8 10394.7 10462.9 10545.9 10590.6 3414.6 3494.8 3607.2 3662.2 3744.0 81930.1 85258.6 87508.0 87538.6 87657.1 8604.3 8644.9 8722.6 8848.5 8980.5 18384.7 18549.9 18619.8 18635.3 18738.8 7228.6 7574.8 7607.7 7787.6 7789.1 3999.4 4025.6 4069.7 4070.2 4088.8 3308.7 3388.6 3455.3 3481.3 3487.2 609.2 614.6 627.9 644.8 655.3 628.3 636.2 642.5 653.6 668.8 8438.9 9210.0 9392.2 9394.5 9457.6 575.1 589.9 620.8 640.0 691.7 4076.8 4468.7 4506.2 4552.8 4565.6 104342.0 109177.0 113128.0 119018.0 123671.5 60043.9 65413.0 67551.7 70457.6 85416.1 68312.7 80240.0 81676.0 85804.0 87030.6 9188.9 9734.2 9769.3 9776.5 9785.6 38438.0 53464.9 55358.9 55632.0 56045.0 73788.0 78070.0 81361.0 82322.2 82770.0 11791.8 12010.3 12095.4 12373.8 12664.7 13574.0 13649.7 13964.7 14127.7 14128.4 12669.4 12743.9 12877.7 12881.1 13018.3 12534.0 12821.6 12864.3 12919.2 13557.7 12662.4 12713.7 12846.2 12890.8 12918.8 10260.3 10325.2 10604.3 11024.4 11340.6 10572.3 11000.9 11225.3 11246.8 11297.0 08:38:06 INFO - PROCESS | 1645 | -------- Summary: end -------- 08:38:06 INFO - PROCESS | 1645 | 08:38:06 INFO - TEST-INFO | 1645: exit 0 08:38:06 INFO - TEST-OK | dromaeo_css | took 1121129ms 08:38:06 INFO - TEST-START | kraken 08:38:06 INFO - operating with platform_type : mac_ 08:38:06 INFO - Initialising browser for kraken test... 08:38:06 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpU1xrBn/profile http://localhost:49241/getInfo.html) 08:38:09 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 08:38:09 INFO - PROCESS | 1652 | colorDepth:24 08:38:09 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 08:38:09 INFO - PROCESS | 1652 | __metrics 08:38:09 INFO - PROCESS | 1652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:38:09 INFO - PROCESS | 1652 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:38:10 INFO - TEST-INFO | 1652: exit 0 08:38:10 INFO - Browser initialized. 08:38:10 INFO - Running cycle 1/1 for kraken test... 08:38:10 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpU1xrBn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:38:22 INFO - PROCESS | 1653 | RSS: Main: 222879744 08:38:22 INFO - PROCESS | 1653 | 08:38:25 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 08:38:26 INFO - PROCESS | 1653 | RSS: Main: 271806464 08:38:26 INFO - PROCESS | 1653 | 08:38:29 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 08:38:29 INFO - PROCESS | 1653 | RSS: Main: 282349568 08:38:29 INFO - PROCESS | 1653 | 08:38:32 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 08:38:33 INFO - PROCESS | 1653 | RSS: Main: 381157376 08:38:33 INFO - PROCESS | 1653 | 08:38:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 08:38:36 INFO - PROCESS | 1653 | RSS: Main: 281481216 08:38:36 INFO - PROCESS | 1653 | 08:38:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:38:38 INFO - PROCESS | 1653 | RSS: Main: 283881472 08:38:38 INFO - PROCESS | 1653 | 08:38:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 08:38:42 INFO - PROCESS | 1653 | RSS: Main: 324841472 08:38:42 INFO - PROCESS | 1653 | 08:38:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 08:38:45 INFO - PROCESS | 1653 | RSS: Main: 326512640 08:38:45 INFO - PROCESS | 1653 | 08:38:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 08:38:49 INFO - PROCESS | 1653 | RSS: Main: 324116480 08:38:49 INFO - PROCESS | 1653 | 08:38:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:38:51 INFO - PROCESS | 1653 | RSS: Main: 299229184 08:38:51 INFO - PROCESS | 1653 | 08:38:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:38:53 INFO - PROCESS | 1653 | RSS: Main: 301494272 08:38:53 INFO - PROCESS | 1653 | 08:38:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:38:56 INFO - PROCESS | 1653 | RSS: Main: 302510080 08:38:56 INFO - PROCESS | 1653 | 08:38:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:39:00 INFO - PROCESS | 1653 | RSS: Main: 321445888 08:39:00 INFO - PROCESS | 1653 | 08:39:03 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:39:04 INFO - PROCESS | 1653 | RSS: Main: 314208256 08:39:04 INFO - PROCESS | 1653 | 08:39:05 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 08:39:06 INFO - PROCESS | 1653 | RSS: Main: 358465536 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | __start_tp_report 08:39:06 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 08:39:06 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 08:39:06 INFO - PROCESS | 1653 | |i|pagename|runs| 08:39:06 INFO - PROCESS | 1653 | |0;ai-astar;116;116;116;115;115;115;116;116;114;115 08:39:06 INFO - PROCESS | 1653 | |1;audio-beat-detection;162;141;140;149;115;108;98;145;177;113 08:39:06 INFO - PROCESS | 1653 | |2;audio-dft;180;185;184;184;185;184;184;186;184;183 08:39:06 INFO - PROCESS | 1653 | |3;audio-fft;79;60;60;89;59;59;59;59;84;59 08:39:06 INFO - PROCESS | 1653 | |4;audio-oscillator;87;70;95;71;68;69;91;67;68;92 08:39:06 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;87;86;86;121;87;84;89;85;88;85 08:39:06 INFO - PROCESS | 1653 | |6;imaging-darkroom;93;93;94;101;150;135;93;93;94;93 08:39:06 INFO - PROCESS | 1653 | |7;imaging-desaturate;83;100;132;78;84;83;84;86;82;82 08:39:06 INFO - PROCESS | 1653 | |8;json-parse-financial;52;56;56;56;58;59;58;57;56;56 08:39:06 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;65;66;76;77;72;69;69;71;69;70 08:39:06 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;86;84;83;86;85;80;84;85;82;86 08:39:06 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;168;156;144;152;158;150;149;150;188;174 08:39:06 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;166;167;168;167;169;165;167;167;168;174 08:39:06 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;68;68;69;67;67;67;68;70;77;85 08:39:06 INFO - PROCESS | 1653 | __end_tp_report 08:39:06 INFO - PROCESS | 1653 | __start_cc_report 08:39:06 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,2061 08:39:06 INFO - PROCESS | 1653 | __end_cc_report 08:39:06 INFO - PROCESS | 1653 | __startTimestamp1480869546028__endTimestamp 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | ------- Summary: start ------- 08:39:06 INFO - PROCESS | 1653 | Number of tests: 14 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:115.40 Median:116.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 08:39:06 INFO - PROCESS | 1653 | Values: 116.0 116.0 116.0 115.0 115.0 115.0 116.0 116.0 114.0 115.0 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:147.00 stddev:25.45 (17.3%) stddev-sans-first:25.02 08:39:06 INFO - PROCESS | 1653 | Values: 162.0 141.0 140.0 149.0 115.0 108.0 98.0 145.0 177.0 113.0 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.60 (0.9%) stddev-sans-first:0.87 08:39:06 INFO - PROCESS | 1653 | Values: 180.0 185.0 184.0 184.0 185.0 184.0 184.0 186.0 184.0 183.0 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:66.70 Median:60.00 stddev:12.18 (20.3%) stddev-sans-first:12.07 08:39:06 INFO - PROCESS | 1653 | Values: 79.0 60.0 60.0 89.0 59.0 59.0 59.0 59.0 84.0 59.0 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.00 stddev:11.78 (14.9%) stddev-sans-first:12.02 08:39:06 INFO - PROCESS | 1653 | Values: 87.0 70.0 95.0 71.0 68.0 69.0 91.0 67.0 68.0 92.0 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:86.50 stddev:11.06 (12.8%) stddev-sans-first:11.69 08:39:06 INFO - PROCESS | 1653 | Values: 87.0 86.0 86.0 121.0 87.0 84.0 89.0 85.0 88.0 85.0 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:103.90 Median:93.00 stddev:20.79 (22.4%) stddev-sans-first:21.68 08:39:06 INFO - PROCESS | 1653 | Values: 93.0 93.0 94.0 101.0 150.0 135.0 93.0 93.0 94.0 93.0 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:89.40 Median:83.00 stddev:16.05 (19.3%) stddev-sans-first:16.86 08:39:06 INFO - PROCESS | 1653 | Values: 83.0 100.0 132.0 78.0 84.0 83.0 84.0 86.0 82.0 82.0 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:56.50 stddev:1.90 (3.4%) stddev-sans-first:1.17 08:39:06 INFO - PROCESS | 1653 | Values: 52.0 56.0 56.0 56.0 58.0 59.0 58.0 57.0 56.0 56.0 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:70.50 stddev:3.84 (5.4%) stddev-sans-first:3.54 08:39:06 INFO - PROCESS | 1653 | Values: 65.0 66.0 76.0 77.0 72.0 69.0 69.0 71.0 69.0 70.0 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:85.00 stddev:1.97 (2.3%) stddev-sans-first:1.96 08:39:06 INFO - PROCESS | 1653 | Values: 86.0 84.0 83.0 86.0 85.0 80.0 84.0 85.0 82.0 86.0 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:158.90 Median:157.00 stddev:13.71 (8.7%) stddev-sans-first:14.15 08:39:06 INFO - PROCESS | 1653 | Values: 168.0 156.0 144.0 152.0 158.0 150.0 149.0 150.0 188.0 174.0 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:167.50 stddev:2.44 (1.5%) stddev-sans-first:2.50 08:39:06 INFO - PROCESS | 1653 | Values: 166.0 167.0 168.0 167.0 169.0 165.0 167.0 167.0 168.0 174.0 08:39:06 INFO - PROCESS | 1653 | 08:39:06 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:68.50 stddev:5.87 (8.6%) stddev-sans-first:6.15 08:39:06 INFO - PROCESS | 1653 | Values: 68.0 68.0 69.0 67.0 67.0 67.0 68.0 70.0 77.0 85.0 08:39:06 INFO - PROCESS | 1653 | -------- Summary: end -------- 08:39:06 INFO - PROCESS | 1653 | 08:39:07 INFO - TEST-INFO | 1653: exit 0 08:39:07 INFO - TEST-OK | kraken | took 60152ms 08:39:07 INFO - SUITE-END | took 1181s 08:39:07 INFO - Completed test suite (00:19:41) 08:39:07 INFO - javascript benchmark 08:39:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3352.497125431186, 3352.748543859211, 3363.276895467477, 3367.915802104947, 3420.2988387380587, 5736.770529178828, 5765.740541675625, 5774.6246493977915, 5791.276090488689, 5859.589828711986, 5739.339975902762, 5806.506446952004, 5824.650520968745, 5832.9708351458185, 5835.241418615574, 5233.476652334764, 5252.3171987641745, 5260.947390526084, 5286.2070689396705, 5301.363836339645, 5222.477752224785, 5241.082810508165, 5247.0, 5354.785808567672, 5359.5444387227135, 5272.31459910213, 5307.283516725251, 5311.203319502067, 5313.3092697949405, 5316.601254905878, 5312.468753124676, 5326.348831862116, 5337.386200586904, 5337.786488540453, 5347.973260133714, 5211.5830733541225, 5245.055889939809, 5260.184671375943, 5268.657537260104, 5276.2085687146855, 5847.239858818326, 5851.765929362863, 5852.209951656514, 5857.297124345063, 5862.912056319158, 5141.897162056738, 6159.7536098556, 6184.19605451293, 6186.072089186613, 6206.193194884681, 6272.0, 6317.178766760338, 6332.620042797447, 6332.841678958017, 6348.0477928310665, 5392.299001129867, 5796.681182534959, 5858.091995740667, 5862.179294898718, 5907.822765317047, 6283.214598175228, 6301.8109456716375, 6307.053941908659, 6332.39842214998, 6346.238451385863, 6068.878622427526, 6100.542459315569, 6135.489990514684, 6145.539084568675, 6182.1345011698395, 6290.370962903765, 6298.779542715983, 6339.239291284983, 6343.904841427382, 6363.0, 5784.797532086357, 6303.842403939938, 6332.936670633235, 6335.493160547145, 6342.968285158545, 5611.8597035073635, 6186.072089186658, 6228.750849966042, 6251.562390632703, 6281.528885333618, 4941.6293777966075, 4942.085714142895, 4947.356843610308, 4963.726995015309, 4983.028309479617, 3811.0, 4458.955410445854, 4472.686911916199, 4474.194644963938, 4503.729776213438, 5765.769369225268, 5774.509166720792, 5827.88344233113, 5903.232579764689, 5956.672383018976, 6341.619502829845, 6372.076048972873, 6373.458256048195, 6376.171097757262, 6381.649009304533, 5760.798372057042, 5810.593258471951, 5924.80363621862, 5939.138824870456, 5991.610545314541, 5317.255584218213, 5330.173823057432, 5388.757505912244, 5441.483059109379, 5458.044842152607, 5363.2759577456545, 6257.061440783918, 6267.592606480564, 6300.653464059521, 6344.68276586178, 6358.618482891041, 6362.077498762561, 6370.649614271074, 6381.8723625528155, 6385.3295403983775, 6206.503479721521, 6322.33615470369, 6327.968360158354, 6330.8733825322315, 6341.239051313872, 5914.911276330945, 6103.542234332354, 6142.191841058572, 6178.0, 6262.029385445263, 6317.4630156437215, 6360.840978975562, 6361.2048493938255, 6362.745490180341, 6363.0, 6114.724837382418, 6201.162843016135, 6203.534734894991, 6217.533684973555, 6234.657093859882, 6031.276246850406, 6096.817095487142, 6121.571489995836, 6205.782797602243, 6209.565330426827, 5997.460228579449, 6045.395460453919, 6063.72713227897, 6079.635221886701, 6101.938980610137, 5994.970025149846, 6065.636061836304, 6090.269167699905, 6186.041163619646, 6188.535859810442, 5898.233229680286, 5905.763769449174, 5915.260592425947, 5927.851803704942, 5946.851328716817], "value": 5742.015758760932, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.22233425007585, 477.1006652460112, 521.849322244451, 530.5516838271661, 533.7430351522167, 38150.42774358383, 39381.212375752504, 39617.20765584687, 39849.4022589661, 41427.0, 40345.19309613806, 40701.18597628046, 40742.0, 40797.18405631885, 40998.18003639925, 8411.747647570583, 8610.182032706884, 9298.907010929906, 9498.620055197784, 9505.66730164441, 11102.222844400854, 11403.258788178742, 11406.828897566502, 11549.498565186555, 11569.768604627903, 11325.263857849255, 11362.147838912073, 11385.601503947364, 11396.0, 11399.48702308398, 9979.151772099307, 11080.448737176801, 11317.547298108066, 11374.488148033359, 11385.601503947322, 11149.832752508719, 11203.551857925673, 11221.541199644076, 11231.045361144312, 11250.54997800087, 38266.80866595677, 38591.80704096489, 38649.22701545953, 38666.293359464566, 38675.41986870171, 3253.658365871573, 3461.152017755641, 3645.471406646048, 3799.8290076946605, 3838.05967537955, 26171.084012059488, 26939.865300673373, 27505.762240699216, 28899.71100288991, 28966.71033289661, 2718.714534973839, 3171.20719820045, 3198.2803869129493, 3269.8855540055984, 3300.6864347886913, 498581.0, 500299.0, 500559.0, 502689.0, 511366.0, 55009.449905501235, 55327.0, 55364.0, 55413.89172216533, 55708.0, 57624.0, 57805.710971444874, 58061.70969145127, 58124.0, 58226.0, 15596.376144954302, 15807.920960395124, 15808.60478488047, 15814.209289535707, 15819.446319378996, 10977.231593788358, 11286.717832054264, 11289.548418063185, 11993.460294286779, 12213.145079844326, 14417.351219195161, 14529.78205326921, 14592.124472531683, 14614.853851461561, 14807.629809254639, 5763.7982670606325, 6790.524663273523, 6884.896726549106, 6916.688749006306, 6921.204061532988, 848.898132224137, 950.4724877692947, 976.1117383181413, 1055.9313644613076, 1093.4642025407493, 27985.72014279872, 28765.56851647227, 29064.982725604503, 29812.701872980993, 29926.55110173349, 1036.383351905615, 1039.8440233964814, 1048.743057950807, 1155.7284038251005, 1169.9002937238968, 12256.81614775779, 14615.78076328856, 15055.774163387558, 15064.472743453927, 15509.302081406138, 636490.0, 638868.0, 640551.0, 640964.0, 644243.0, 55477.0, 55508.44491555113, 55510.612234694454, 55559.44440555543, 55580.0, 1238.8141778733261, 1272.3638180909545, 1355.1191725378583, 1381.081580748811, 1404.9929750351182, 29202.269943251587, 29873.253168670955, 29883.0, 29993.55009674813, 30559.0, 42626.78686606547, 43004.0, 43075.70772876819, 43120.56879431166, 43160.568394315655, 528284.0, 530257.0, 531724.0, 533793.0, 533796.0, 24777.50444991054, 25690.35774105662, 25785.226443206735, 26287.4742505145, 26346.73653263443, 43641.12717745564, 43668.56331436645, 44113.0, 44532.77733611311, 44859.327110094026, 36398.636013640586, 36489.63510364862, 37060.25879482449, 38378.808105959295, 38400.2319953605, 36471.27057458889, 36620.63379366172, 37182.44226336553, 37275.0, 38236.0, 35842.28315433625, 36338.0, 36360.63639363572, 36380.36288367083, 37037.629623704495, 31277.218069548442, 33315.66684333126, 33387.83306083454, 33405.0, 33527.329453410304, 24332.391690207885, 24495.0, 24499.510009800062, 24868.12961546337, 24939.251822445356], "value": 19505.154695362657, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.461434776097, 348.5817019576508, 352.4273056283539, 372.6925286638522, 382.2126419575674, 12307.446164922589, 15701.92149039256, 16852.49442516725, 20185.0, 20318.69521957168, 18607.627847443044, 19626.705599415985, 19654.90172549139, 19658.0, 19692.01539923005, 4069.3285607874664, 5493.2034854946005, 5754.02181629122, 5755.6258843175265, 5773.884522309551, 4226.239276930151, 6099.420555047263, 6331.810045698637, 6440.420362167404, 6445.484361251112, 6170.8087846796425, 6276.0, 6360.809175724736, 6377.298497165308, 6405.743770249184, 6328.873422531547, 6360.077788720656, 6363.300036995926, 6363.713632886531, 6377.617342959391, 5311.176767601028, 5485.396606373296, 5541.972290138564, 5548.91676624851, 5570.888582228333, 24095.036198552036, 24703.876480617662, 24775.628365574532, 24887.128950486647, 25143.37141571457, 2260.2202240227093, 2300.8946813665475, 2374.3470545599926, 2561.7950563954837, 2754.6556680414947, 14891.925540372229, 14979.176145312003, 14980.47568335114, 15671.843281567153, 15855.762163567555, 1725.6376161006203, 1839.8804077734903, 1855.7030875059925, 1859.8605104617209, 1861.655593715167, 86329.0, 102962.48518757358, 103313.4834325831, 104302.47848760783, 104508.43237351446, 8969.372143949991, 10466.110380617733, 10648.946755266328, 11049.226554141133, 11221.158413119048, 13895.235762032982, 13907.513237036857, 13941.442342306194, 13943.860561394458, 13971.371288292052, 8312.0, 8555.187257210555, 8643.222110010129, 8692.521911294813, 8704.0, 8562.657493700182, 8565.944173398464, 8575.756515305246, 8702.956485217534, 8727.516322225238, 5863.560232982543, 5987.9221740087205, 6551.443127334134, 6557.506684524963, 6580.86838263232, 1836.6877630802796, 1847.2057015483203, 1862.2178684952082, 1871.279557370395, 1888.310766570192, 723.4501778648265, 743.2678811370796, 847.9872801907976, 852.5651917521984, 923.8290916180528, 17789.288428462718, 20307.28924487636, 20878.791212087686, 21582.676259856114, 21659.35021949344, 934.9766255843522, 951.3007939164709, 954.4082668745283, 983.6114734679904, 1017.9949100254452, 10787.029167374974, 11303.265287756269, 11319.830202546967, 11334.60328888485, 11338.60314888975, 578140.0, 581609.0, 584459.0, 589869.0, 597994.0, 67868.6606566974, 122006.0, 123115.38442307731, 123291.0, 124537.0, 942.3168203052869, 956.3831328793028, 981.8134554434778, 983.0611765763679, 983.5672304186136, 18758.906205468884, 19155.808441915404, 19249.807501925363, 19267.421977340702, 19286.517837054187, 52340.47659523356, 52585.7370713144, 53399.733001334745, 53768.0, 54671.906561869335, 54158.042615313076, 72178.55642887008, 75435.24564754282, 75459.24540754742, 75763.62118189594, 7521.548707077593, 7852.528848269122, 8115.715949941724, 8139.715109971123, 8284.46151000183, 11870.703232419259, 12895.41970611306, 12930.0, 13276.933615331862, 13344.132631378929, 11068.225224234548, 11259.268147570381, 11430.371329576954, 11696.532138714356, 11726.70683232926, 11043.889561104286, 11231.38227397501, 11344.886551134714, 11612.361320127475, 11756.882431175922, 11497.252678575866, 11534.423278836193, 11650.592229271935, 11960.521579136737, 11978.281303121841, 7971.402144839044, 7975.481593696391, 8228.0, 8362.874556881774, 8374.785656079712, 11202.0, 11239.269447485887, 11352.659420217407, 11556.653300401002, 11606.419679016184], "value": 9590.292954962055, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.3545404567944, 1473.9852601473985, 1576.5900865774895, 1775.547235454958, 1782.224732241476, 8031.237032481919, 8451.0, 8462.449940753846, 8477.872831907527, 8481.406301558893, 7375.225601311866, 7609.92390076099, 7618.466707330489, 7914.129445760962, 8272.090070092285, 1962.6565351063578, 2268.7731226877263, 2306.573283942476, 2316.9652455213186, 2319.93040208794, 2295.2769877488536, 2331.207389487574, 2345.566070277004, 2347.8943447544816, 2351.1653363056125, 1890.922174360615, 1951.677973134431, 2301.0335659023167, 2321.767823217673, 2326.081197926818, 2270.761570035147, 2293.0827668932393, 2294.380517260347, 2299.9425014374774, 2301.390131615115, 2283.8058765004994, 2325.7092863392077, 2327.592671282519, 2330.8951097200666, 2331.976680233193, 8248.958755206246, 8289.378296627716, 8332.62503187352, 8362.790930226733, 8389.203025712548, 5060.367454068241, 5187.922181167285, 5212.6611770235195, 5217.113090774577, 5218.347706536683, 3017.788754787166, 3244.7890887092262, 3450.102973226954, 3508.4737289406794, 3518.9648103518894, 937.864009718587, 1006.808706345792, 1050.144132531984, 1082.7293176705823, 1088.2708585247824, 10755.247132700713, 10881.945590271998, 10901.0, 10908.072813810835, 10917.945410272898, 4564.406627138451, 4726.71639701619, 4752.5485078917445, 4767.094252092161, 4780.25905984566, 8667.349948753868, 8710.477371357612, 8730.69442569507, 8738.432001919855, 8759.649614015369, 5086.21163719624, 5105.387353517527, 5113.386393632788, 5113.846584602468, 5119.0, 4595.356650068993, 4754.453237877619, 4797.18447863864, 4823.5658790709, 4861.100696371112, 3598.9280214395567, 3667.2298817248675, 3692.2061756722433, 3736.831842567091, 3754.4743735877, 1137.863456385239, 1139.5954436175205, 1149.0290704354807, 1150.649052039136, 1157.0743405275746, 761.919998400171, 766.9156392796788, 845.0999685335221, 873.3100850328296, 885.9103302937423, 5405.081136206854, 5452.509274165265, 5563.499285064364, 5618.129189975561, 5622.437756224426, 352.3288136100732, 428.9335153051282, 433.8763452416109, 436.2997389190367, 444.034225559407, 3719.628037196259, 3779.055236190952, 3799.0, 3812.1803812180515, 3906.8046597670004, 9672.70981870545, 10170.491475426199, 10190.592376304865, 10192.694219173436, 10241.436720980417, 10088.949555252177, 10103.696889093339, 10134.493275336205, 10159.085682288629, 10170.08469237772, 7634.6182690865235, 7739.0, 7763.146053934062, 7922.0889597697005, 7949.920500795034, 5374.3013408256675, 5459.0, 5491.148871924858, 5520.033994051106, 5735.082386818089, 8231.835363292821, 9264.768380790534, 9299.535023248674, 9394.201492873182, 9421.858672119786, 6911.481638877205, 8768.78078048054, 8830.823383532164, 9515.476648784383, 9626.66306679263, 6611.206655201407, 6771.085903402977, 6775.254721980479, 6775.661216939232, 6785.558938668971, 7569.886451703339, 7722.53664780115, 7839.137694853675, 7844.960775196316, 7850.018747656543, 7009.474289428415, 7047.40097091753, 7114.679839407136, 7212.639368031472, 7289.963550182215, 6937.7571784987285, 7030.648467576703, 7037.577745335297, 7049.753258635923, 7058.400035996792, 6568.474522038321, 6576.506761992774, 6602.273749887605, 6606.735730570724, 6684.799456016327, 4668.976655116703, 4765.76171191446, 4828.130936431476, 4830.903381932412, 4847.75761211931, 4772.117158325721, 4772.570468657837, 4853.320535125014, 4913.950860491493, 5056.924146137737], "value": 4257.6403259856315, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.41138191907703, 307.9892203772867, 312.7826160818231, 318.6733598061986, 322.5484321949272, 4790.664653474259, 5248.6063545234065, 5439.891202175954, 5522.3649280332775, 5539.086050801618, 5101.7755738622745, 5242.135047717132, 5378.542823859967, 5406.89158722462, 5472.0, 3376.3416133853907, 3470.171809974875, 3914.628110329528, 4179.874603761892, 4188.937165942498, 3308.7518436117266, 3704.4258139988347, 4188.078622703016, 4193.706440549163, 4219.683523735732, 4129.483814523184, 4162.43807086044, 4170.332746760519, 4175.227582897159, 4206.978965105185, 3813.866514671973, 3883.825227864753, 4125.8751742852455, 4161.191195942742, 4175.54069052404, 3406.9608769325346, 3542.1321776164746, 3560.2345495718287, 3622.728295377857, 3626.202235508184, 4207.537170911197, 5343.492368225013, 5347.946520534783, 5358.705271210082, 5359.437259087778, 4556.746894603977, 4695.835645752416, 4705.458872229703, 4723.220668589679, 4729.533844508213, 5427.972860135674, 5681.0, 5734.455226753452, 5744.22452968848, 5745.597808153389, 3315.535824984504, 3917.2372432405245, 3917.9216415671526, 3923.0, 3927.9410808837893, 5967.701614919237, 6008.939910600881, 6017.7592896284095, 6038.728257228391, 6054.3945605439985, 3776.50905382304, 3936.2717897189114, 3962.5046869141356, 3985.3822657488136, 4009.7794621296107, 3363.327334533103, 3443.8648038215965, 3540.2919416116365, 3720.720945929066, 4034.3948407739076, 4155.792210389468, 4388.517263101056, 4689.62483001359, 4702.224133018082, 4711.869151403638, 4587.72473651582, 5438.91841622376, 5548.1427065061525, 5703.201551782754, 5746.655200687972, 1223.2843786384888, 1241.8199361092773, 1247.65689435406, 1257.6415721519322, 1269.631806776043, 810.1898101898101, 866.7562048460479, 877.087828658201, 879.7668617816283, 882.2236431939851, 4615.446146462379, 4712.293156026555, 5013.89972200554, 5256.290400795843, 5280.577553795687, 4773.04539092176, 5089.389273287229, 5097.362829646294, 5236.895262094813, 5666.235058267163, 4031.7580945143386, 4231.61915427613, 4873.610111191168, 5020.171671674133, 5163.4062082860955, 2614.535859918452, 2873.5976963225166, 2881.3228891210547, 2901.2746813296676, 2910.4138244656715, 4979.6265280104135, 5314.5748340132695, 5439.211314359396, 5667.914981275284, 5748.741306641211, 4809.326694262806, 4870.076319853638, 5066.265391518209, 5210.7915683373, 5470.699111548903, 5225.216217567396, 5304.336957880265, 5673.290838645169, 5709.972205002973, 5731.512821410227, 5001.899962000813, 5150.381954165524, 5471.759386475514, 5542.473465020817, 5563.527100196528, 4708.741019243975, 4713.2458806590785, 5201.323827902348, 5453.15476101205, 5455.917406665221, 4874.854409213903, 5185.559227465556, 5659.377468478547, 5774.0, 5803.506701930383, 3025.4100450411956, 3988.0628052407083, 4010.2981978153125, 4010.7593544387432, 4039.646718349386, 5774.3648198697265, 5860.619059761102, 5950.2810743285445, 5986.760529578768, 6034.39656034393, 4935.383077115361, 5072.188449848007, 5179.249008893614, 5536.698875764112, 5859.384764599656, 5733.04419950233, 5752.964466396089, 5757.625754325955, 5778.422157784188, 5782.207515670156, 5369.972520609543, 5435.83692489226, 5573.7213139343685, 5575.968445837533, 5666.600039975981, 4957.231629097496, 5745.224394706661, 5773.278340207474, 5775.768969241183, 5816.331121921034, 4928.556429921325, 5097.643164978416, 5289.894202116013, 5613.410591887793, 5626.915596266142], "value": 4061.717290612821, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [642.8296501427121, 741.8775901976175, 775.6897241103561, 784.3098073695147, 805.8428606421749, 13744.381502832372, 13943.442262309509, 14279.286035698226, 14446.927765361184, 14647.92676036621, 12834.743305133916, 13031.543895963643, 13158.473661053546, 13164.210147391163, 13171.73656526869, 778.1906816910406, 779.5790273252451, 809.3080416244114, 809.8987626546682, 811.119934870666, 761.6458346868733, 765.8276887700279, 767.0411985018727, 776.6000509737489, 801.7274126796888, 846.8137009857821, 847.0, 848.2323497234981, 848.8811566380681, 851.5997481183833, 839.5382539603194, 839.9286060684849, 841.4530555139147, 842.9620667069996, 843.3927572148043, 620.9782657607008, 622.3993845938691, 624.1636207481996, 625.2684359299585, 627.0594108836744, 12040.638780836589, 12363.938180309131, 12395.62813115608, 12408.689782755413, 12410.937945310307, 11636.243644163102, 11674.066074714088, 11832.763344733143, 11907.940460297643, 11942.761144777056, 10265.794684106351, 10394.688159355232, 10462.947685261524, 10545.9472702636, 10590.629327973562, 3414.590249170091, 3494.790312581253, 3607.2424790793966, 3662.1943172502006, 3743.9625603743884, 81930.09201555302, 85258.57370713107, 87508.0, 87538.5623071887, 87657.12342876554, 8604.268637165911, 8644.870326945102, 8722.595089479899, 8848.46909185451, 8980.506072166094, 18384.724229136573, 18549.90725046366, 18619.789713668568, 18635.347762828234, 18738.81261187398, 7228.590424867206, 7574.848503030019, 7607.733729319447, 7787.5716835574585, 7789.143194248628, 3999.3800960851113, 4025.6430676116415, 4069.654079403284, 4070.1859628074503, 4088.8160032798596, 3308.71875890552, 3388.5594872666397, 3455.274392377579, 3481.2863363010524, 3487.163080860575, 609.2354095609982, 614.5882258886556, 627.9058141278754, 644.8033349828254, 655.3381085104079, 628.2523796681934, 636.1507387637463, 642.5437939063318, 653.5849735418044, 668.8327918020495, 8438.915610843937, 9210.03294654065, 9392.21565834174, 9394.530273486298, 9457.621695132118, 575.0942265931175, 589.8672798620362, 620.7610070122945, 640.0143778580933, 691.6991108867659, 4076.8369265229057, 4468.664850136252, 4506.233940230169, 4552.818056231914, 4565.56627120423, 104341.95658043474, 109177.0, 113128.0, 119018.0, 123671.5265694699, 60043.85527758467, 65413.0, 67551.66224168945, 70457.6477117611, 85416.14583854206, 68312.65843670849, 80240.0, 81676.0, 85804.0, 87030.56484717662, 9188.862167067633, 9734.221262298857, 9769.316147869866, 9776.462294573867, 9785.608575656894, 38438.0, 53464.93070138653, 55358.89282214252, 55632.0, 56045.0, 73788.0, 78070.0, 81361.0, 82322.17677823384, 82770.0, 11791.76416471683, 12010.279383237033, 12095.39523023863, 12373.75252494963, 12664.74670506603, 13573.981951353886, 13649.658758531115, 13964.720705586034, 14127.717445651235, 14128.43486260538, 12669.429875655433, 12743.93628031891, 12877.742445150858, 12881.098323117667, 13018.28399438002, 12534.0, 12821.615351539469, 12864.292463914575, 12919.22484650924, 13557.661058473617, 12662.366881656064, 12713.745725085631, 12846.229226246454, 12890.849415255923, 12918.806217906551, 10260.281780275309, 10325.173986081245, 10604.310719803188, 11024.44877756125, 11340.6030788922, 10572.259941803999, 11000.889991100306, 11225.270357426742, 11246.831297530707, 11297.0], "value": 7054.296421768857, "unit": "score"}], "name": "dromaeo_css", "value": 7126.960367876437, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 115.0, 115.0, 115.0, 116.0, 116.0, 114.0, 115.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 141.0, 140.0, 149.0, 115.0, 108.0, 98.0, 145.0, 177.0, 113.0], "value": 134.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 185.0, 184.0, 184.0, 185.0, 184.0, 184.0, 186.0, 184.0, 183.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 60.0, 89.0, 59.0, 59.0, 59.0, 59.0, 84.0, 59.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 95.0, 71.0, 68.0, 69.0, 91.0, 67.0, 68.0, 92.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 121.0, 87.0, 84.0, 89.0, 85.0, 88.0, 85.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 101.0, 150.0, 135.0, 93.0, 93.0, 94.0, 93.0], "value": 103.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 100.0, 132.0, 78.0, 84.0, 83.0, 84.0, 86.0, 82.0, 82.0], "value": 89.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 56.0, 56.0, 56.0, 58.0, 59.0, 58.0, 57.0, 56.0, 56.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 76.0, 77.0, 72.0, 69.0, 69.0, 71.0, 69.0, 70.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 83.0, 86.0, 85.0, 80.0, 84.0, 85.0, 82.0, 86.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 156.0, 144.0, 152.0, 158.0, 150.0, 149.0, 150.0, 188.0, 174.0], "value": 158.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 168.0, 167.0, 169.0, 165.0, 167.0, 167.0, 168.0, 174.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 67.0, 67.0, 67.0, 68.0, 70.0, 77.0, 85.0], "value": 70.6, "unit": "score"}], "name": "kraken", "value": 1469.8999999999999, "alertThreshold": 2.0}]} 08:39:07 INFO - Return code: 0 08:39:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:39:07 INFO - # TBPL SUCCESS # 08:39:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:39:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:39:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:39:07 INFO - 2016-12-04 08:39:07.311 screenresolution[1657:10677] starting screenresolution argv=screenresolution get 08:39:07 INFO - 2016-12-04 08:39:07.314 screenresolution[1657:10677] Display 0: 1600x1200x32@60 08:39:07 INFO - 2016-12-04 08:39:07.320 screenresolution[1658:10680] starting screenresolution argv=screenresolution list 08:39:07 INFO - Available Modes on Display 0 08:39:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:39:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:39:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:39:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:39:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:39:07 INFO - Intel Iris: 08:39:07 INFO - Chipset Model: Intel Iris 08:39:07 INFO - Type: GPU 08:39:07 INFO - Bus: Built-In 08:39:07 INFO - VRAM (Dynamic, Max): 1536 MB 08:39:07 INFO - Vendor: Intel (0x8086) 08:39:07 INFO - Device ID: 0x0a2e 08:39:07 INFO - Revision ID: 0x0009 08:39:07 INFO - Displays: 08:39:07 INFO - Display: 08:39:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:39:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:39:07 INFO - Display Serial Number: 1600x1200 60 08:39:07 INFO - Main Display: Yes 08:39:07 INFO - Mirror: Off 08:39:07 INFO - Online: Yes 08:39:07 INFO - Rotation: Supported 08:39:07 INFO - Return code: 0 08:39:07 INFO - Running post-action listener: _resource_record_post_action 08:39:07 INFO - [mozharness: 2016-12-04 16:39:07.566310Z] Finished run-tests step (success) 08:39:07 INFO - Running post-run listener: _resource_record_post_run 08:39:07 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 33442816; Write bytes: 658791424; Read time: 332; Write time: 1882 08:39:07 INFO - TinderboxPrint: CPU usage
53.6% 08:39:07 INFO - TinderboxPrint: I/O read bytes / time
33,442,816 / 332 08:39:07 INFO - TinderboxPrint: I/O write bytes / time
658,791,424 / 1,882 08:39:07 INFO - TinderboxPrint: CPU idle
2,252.6 (46.4%) 08:39:07 INFO - TinderboxPrint: CPU system
971.6 (20.0%) 08:39:07 INFO - TinderboxPrint: CPU user
1,629.4 (33.6%) 08:39:07 INFO - TinderboxPrint: Swap in / out
378,413,056 / 0 08:39:07 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 344787456; Write bytes: 345662976; Read time: 26624; Write time: 679 08:39:07 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 31472640; Write bytes: 306152960; Read time: 311; Write time: 1174 08:39:08 INFO - Running post-run listener: _upload_blobber_files 08:39:08 INFO - Blob upload gear active. 08:39:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:39:08 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:39:08 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:39:08 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:39:08 INFO - (blobuploader) - INFO - Open directory for files ... 08:39:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:39:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:39:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:39:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:39:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:39:09 INFO - (blobuploader) - INFO - Done attempting. 08:39:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:39:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:39:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:39:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:39:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:39:10 INFO - (blobuploader) - INFO - Done attempting. 08:39:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:39:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:39:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:39:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:39:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:39:13 INFO - (blobuploader) - INFO - Done attempting. 08:39:13 INFO - (blobuploader) - INFO - Iteration through files over. 08:39:13 INFO - Return code: 0 08:39:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:39:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:39:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef86db5507368dcc658d815f777a1fc63853003aa4677657017aea13742d96f1756e80e6bd2c5dabc3caeed64f5c19deaef37ba63c94d4fa025fea6b496ec0dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4635bada430c24c54ca0a25892cabd7b1686afbd89cdf95f19ae73af54ec7a589d3eb15487b895577a0f2f2d941b0c0515cf6a4c950890c9294651959abbc4d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c84b62a311d5d433e62678eab0378d84ab4d1eda148dd2a9206a666a8523585ca4157427fca1e6508719f0ed7389384bd4bdbe49622e27acfae133d39b1ac1da"} 08:39:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:39:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:39:13 INFO - Contents: 08:39:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef86db5507368dcc658d815f777a1fc63853003aa4677657017aea13742d96f1756e80e6bd2c5dabc3caeed64f5c19deaef37ba63c94d4fa025fea6b496ec0dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4635bada430c24c54ca0a25892cabd7b1686afbd89cdf95f19ae73af54ec7a589d3eb15487b895577a0f2f2d941b0c0515cf6a4c950890c9294651959abbc4d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c84b62a311d5d433e62678eab0378d84ab4d1eda148dd2a9206a666a8523585ca4157427fca1e6508719f0ed7389384bd4bdbe49622e27acfae133d39b1ac1da"} 08:39:13 INFO - Running post-run listener: copy_logs_to_upload_dir 08:39:13 INFO - Copying logs to upload dir... 08:39:13 INFO - mkdir: /builds/slave/test/build/upload/logs 08:39:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.816213 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-04 08:39:13.089085) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-04 08:39:13.092419) ========= 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.4bbo1fn3AR/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.mNwztwvZVH/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef86db5507368dcc658d815f777a1fc63853003aa4677657017aea13742d96f1756e80e6bd2c5dabc3caeed64f5c19deaef37ba63c94d4fa025fea6b496ec0dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4635bada430c24c54ca0a25892cabd7b1686afbd89cdf95f19ae73af54ec7a589d3eb15487b895577a0f2f2d941b0c0515cf6a4c950890c9294651959abbc4d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c84b62a311d5d433e62678eab0378d84ab4d1eda148dd2a9206a666a8523585ca4157427fca1e6508719f0ed7389384bd4bdbe49622e27acfae133d39b1ac1da"} build_url:https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011088 build_url: 'https://queue.taskcluster.net/v1/task/aDqioS8oQBC_pdBU1mL30A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef86db5507368dcc658d815f777a1fc63853003aa4677657017aea13742d96f1756e80e6bd2c5dabc3caeed64f5c19deaef37ba63c94d4fa025fea6b496ec0dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4635bada430c24c54ca0a25892cabd7b1686afbd89cdf95f19ae73af54ec7a589d3eb15487b895577a0f2f2d941b0c0515cf6a4c950890c9294651959abbc4d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c84b62a311d5d433e62678eab0378d84ab4d1eda148dd2a9206a666a8523585ca4157427fca1e6508719f0ed7389384bd4bdbe49622e27acfae133d39b1ac1da"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-04 08:39:13.126778) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 08:39:13.127162) ========= 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.4bbo1fn3AR/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.mNwztwvZVH/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.005300 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 08:39:13.176021) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-04 08:39:13.176510) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-04 08:39:13.176882) ========= ========= Total master_lag: 0.18 =========