builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0173 starttime: 1484435484.16 results: success (0) buildid: 20170114140724 builduid: f90f2fa9824a407b8db1fe35f58261eb revision: 1e04cc4582dd86864035f0aed5fac81907dea848 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:24.163270) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:24.163711) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:24.164029) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:24.190275) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:24.190564) ========= 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.dW78mkqDca/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.HviZ8ix1Sk/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-14 15:11:24-- 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% 14.1M=0.001s 2017-01-14 15:11:24 (14.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.629127 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:24.843680) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:24.844013) ========= 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.dW78mkqDca/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.HviZ8ix1Sk/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.027218 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:24.891848) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-14 15:11:24.892206) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1e04cc4582dd86864035f0aed5fac81907dea848 --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 1e04cc4582dd86864035f0aed5fac81907dea848 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dW78mkqDca/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.HviZ8ix1Sk/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-14 15:11:24,982 truncating revision to first 12 chars 2017-01-14 15:11:24,982 Setting DEBUG logging. 2017-01-14 15:11:24,983 attempt 1/10 2017-01-14 15:11:24,983 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1e04cc4582dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-14 15:11:25,743 unpacking tar archive at: mozilla-aurora-1e04cc4582dd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.030128 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-14 15:11:25.945560) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:25.945912) ========= script_repo_revision: 1e04cc4582dd86864035f0aed5fac81907dea848 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:25.946312) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:25.946577) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:25.966491) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-14 15:11:25.966778) ========= /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.dW78mkqDca/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.HviZ8ix1Sk/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:11:26 INFO - MultiFileLogger online at 20170114 15:11:26 in /builds/slave/test 15:11:26 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 15:11:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:11:26 INFO - {'append_to_log': False, 15:11:26 INFO - 'base_work_dir': '/builds/slave/test', 15:11:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:11:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:11:26 INFO - 'branch': 'Mozilla-Aurora', 15:11:26 INFO - 'buildbot_json_path': 'buildprops.json', 15:11:26 INFO - 'config_files': ('talos/mac_config.py',), 15:11:26 INFO - 'default_actions': ('clobber', 15:11:26 INFO - 'read-buildbot-config', 15:11:26 INFO - 'download-and-extract', 15:11:26 INFO - 'populate-webroot', 15:11:26 INFO - 'create-virtualenv', 15:11:26 INFO - 'install', 15:11:26 INFO - 'run-tests'), 15:11:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:11:26 INFO - 'download_minidump_stackwalk': True, 15:11:26 INFO - 'download_symbols': 'ondemand', 15:11:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:11:26 INFO - 'tooltool.py': '/tools/tooltool.py', 15:11:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:11:26 INFO - '/tools/misc-python/virtualenv.py')}, 15:11:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:11:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:11:26 INFO - 'installer_path': 'installer.exe', 15:11:26 INFO - 'log_level': 'info', 15:11:26 INFO - 'log_name': 'talos', 15:11:26 INFO - 'log_to_console': True, 15:11:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:11:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:11:26 INFO - 'opt_config_files': (), 15:11:26 INFO - 'pip_index': False, 15:11:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:11:26 INFO - 'cmd': ('bash', 15:11:26 INFO - '-c', 15:11:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:11:26 INFO - 'enabled': True, 15:11:26 INFO - 'halt_on_failure': False, 15:11:26 INFO - 'name': 'check_screen_resolution'},), 15:11:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:11:26 INFO - 'cmd': ('bash', 15:11:26 INFO - '-c', 15:11:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:11:26 INFO - 'enabled': True, 15:11:26 INFO - 'halt_on_failure': False, 15:11:26 INFO - 'name': 'check_screen_resolution'},), 15:11:26 INFO - 'run_cmd_checks_enabled': True, 15:11:26 INFO - 'sps_profile': False, 15:11:26 INFO - 'sps_profile_interval': 0, 15:11:26 INFO - 'suite': 'dromaeojs', 15:11:26 INFO - 'system_bits': '32', 15:11:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:11:26 INFO - 'title': 't-yosemite-r7-0173', 15:11:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:11:26 INFO - 'use_talos_json': True, 15:11:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:11:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:11:26 INFO - 'work_dir': 'build'} 15:11:26 INFO - [mozharness: 2017-01-14 23:11:26.176533Z] Running clobber step. 15:11:26 INFO - Running pre-action listener: _resource_record_pre_action 15:11:26 INFO - Running main action method: clobber 15:11:26 INFO - rmtree: /builds/slave/test/build 15:11:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:11:28 INFO - Running post-action listener: _resource_record_post_action 15:11:28 INFO - [mozharness: 2017-01-14 23:11:28.455996Z] Finished clobber step (success) 15:11:28 INFO - [mozharness: 2017-01-14 23:11:28.456118Z] Running read-buildbot-config step. 15:11:28 INFO - Running pre-action listener: _resource_record_pre_action 15:11:28 INFO - Running main action method: read_buildbot_config 15:11:28 INFO - Using buildbot properties: 15:11:28 INFO - { 15:11:28 INFO - "project": "", 15:11:28 INFO - "product": "firefox", 15:11:28 INFO - "script_repo_revision": "production", 15:11:28 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:11:28 INFO - "repository": "", 15:11:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:11:28 INFO - "buildid": "20170114140724", 15:11:28 INFO - "pgo_build": "False", 15:11:28 INFO - "basedir": "/builds/slave/test", 15:11:28 INFO - "buildnumber": 41, 15:11:28 INFO - "slavename": "t-yosemite-r7-0173", 15:11:28 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:11:28 INFO - "platform": "yosemite_r7", 15:11:28 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:11:28 INFO - "branch": "mozilla-aurora", 15:11:28 INFO - "repo_path": "releases/mozilla-aurora", 15:11:28 INFO - "revision": "1e04cc4582dd86864035f0aed5fac81907dea848", 15:11:28 INFO - "stage_platform": "macosx64", 15:11:28 INFO - "builduid": "f90f2fa9824a407b8db1fe35f58261eb", 15:11:28 INFO - "slavebuilddir": "test" 15:11:28 INFO - } 15:11:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 15:11:28 INFO - Running post-action listener: _resource_record_post_action 15:11:28 INFO - [mozharness: 2017-01-14 23:11:28.459496Z] Finished read-buildbot-config step (success) 15:11:28 INFO - [mozharness: 2017-01-14 23:11:28.459607Z] Running download-and-extract step. 15:11:28 INFO - Running pre-action listener: _resource_record_pre_action 15:11:28 INFO - Running main action method: download_and_extract 15:11:28 INFO - mkdir: /builds/slave/test/build/tests 15:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:28 INFO - trying https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 15:11:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/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 15:11:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/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 15:11:29 INFO - Downloaded 1199 bytes. 15:11:29 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:11:29 INFO - Using the following test package requirements: 15:11:29 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:11:29 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:29 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 15:11:29 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:29 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 15:11:29 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:29 INFO - u'jsshell-mac.zip'], 15:11:29 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:29 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 15:11:29 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:11:29 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:29 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 15:11:29 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:29 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 15:11:29 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:29 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 15:11:29 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:29 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 15:11:29 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:11:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:11:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:11:29 INFO - Fetch https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:11:30 INFO - Http code: 200 15:11:30 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 15:11:30 INFO - x-amz-cf-id: 0CJUHHsMDkObE4uzNPdKrsZ7N4dQFeqc1GJv8EeCOOtCFjx-ejhJPw== 15:11:30 INFO - x-amz-version-id: qIinis5NuhIPH_MYtY1bGhHpdm.rtZ0L 15:11:30 INFO - Expected file size: 16275666 15:11:30 INFO - Obtained file size: 16275666 15:11:37 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 15:11:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:11:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:11:37 INFO - Fetch https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:11:37 INFO - Http code: 200 15:11:37 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 15:11:37 INFO - x-amz-cf-id: cQ1zP5bdV36iEPzKuBMCzkowPuoKwQ9j45EQkyVLeqyfUJg6MmwcRg== 15:11:37 INFO - x-amz-version-id: qIinis5NuhIPH_MYtY1bGhHpdm.rtZ0L 15:11:38 INFO - Expected file size: 16275666 15:11:38 INFO - Obtained file size: 16275666 15:11:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 15:11:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:11:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 15:11:44 INFO - Http code: 200 15:11:44 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 15:11:44 INFO - x-amz-cf-id: 1lIwb6gE7LcDR9sPzJ05czNA8A9eB5nWaNSTRXdVlC-CEgRIcjWugQ== 15:11:44 INFO - x-amz-version-id: XhYLRgJFHXgzH1IGf4nOk7CBNGt4b.ka 15:11:45 INFO - Expected file size: 14409038 15:11:45 INFO - Obtained file size: 14409038 15:11:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:45 INFO - trying https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:11:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 15:11:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/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 15:11:48 INFO - Downloaded 109991904 bytes. 15:11:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:11:48 INFO - mkdir: /builds/slave/test/properties 15:11:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:11:48 INFO - Writing to file /builds/slave/test/properties/build_url 15:11:48 INFO - Contents: 15:11:48 INFO - build_url:https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:11:48 INFO - Running post-action listener: _resource_record_post_action 15:11:48 INFO - Running post-action listener: set_extra_try_arguments 15:11:48 INFO - [mozharness: 2017-01-14 23:11:48.970015Z] Finished download-and-extract step (success) 15:11:48 INFO - [mozharness: 2017-01-14 23:11:48.970133Z] Running populate-webroot step. 15:11:48 INFO - Running pre-action listener: _resource_record_pre_action 15:11:48 INFO - Running main action method: populate_webroot 15:11:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:11:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:11:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:11:48 INFO - '--tppagecycles', 15:11:48 INFO - '7'], 15:11:48 INFO - 'tests': ['tsvgm']}}, 15:11:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:11:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:11:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:11:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:11:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:11:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:11:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:48 INFO - 'talos_options': ['--disable-e10s'], 15:11:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:11:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:11:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:48 INFO - 'talos_options': ['--disable-e10s'], 15:11:48 INFO - 'tests': ['damp', 'tps']}, 15:11:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:48 INFO - 'tests': ['damp', 'tps']}, 15:11:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:11:48 INFO - 'tests': ['dromaeo_dom']}, 15:11:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:11:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:11:48 INFO - 'tests': ['basic_compositor_video']}, 15:11:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:11:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:11:48 INFO - 'tests': ['a11yr', 15:11:48 INFO - 'ts_paint', 15:11:48 INFO - 'tpaint', 15:11:48 INFO - 'sessionrestore', 15:11:48 INFO - 'sessionrestore_no_auto_restore', 15:11:48 INFO - 'tabpaint']}, 15:11:48 INFO - 'other-e10s': {'tests': ['a11yr', 15:11:48 INFO - 'ts_paint', 15:11:48 INFO - 'tpaint', 15:11:48 INFO - 'sessionrestore', 15:11:48 INFO - 'sessionrestore_no_auto_restore', 15:11:48 INFO - 'tabpaint']}, 15:11:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:11:48 INFO - 'ts_paint', 15:11:48 INFO - 'tpaint', 15:11:48 INFO - 'sessionrestore', 15:11:48 INFO - 'sessionrestore_no_auto_restore', 15:11:48 INFO - 'tabpaint']}, 15:11:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:11:48 INFO - 'ts_paint', 15:11:48 INFO - 'tpaint', 15:11:48 INFO - 'sessionrestore', 15:11:48 INFO - 'sessionrestore_no_auto_restore', 15:11:48 INFO - 'tabpaint']}, 15:11:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:11:48 INFO - 'tests': ['a11yr', 15:11:48 INFO - 'ts_paint', 15:11:48 INFO - 'tpaint', 15:11:48 INFO - 'sessionrestore', 15:11:48 INFO - 'sessionrestore_no_auto_restore', 15:11:48 INFO - 'tabpaint']}, 15:11:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:11:48 INFO - 'tests': ['a11yr', 15:11:48 INFO - 'ts_paint', 15:11:48 INFO - 'tpaint', 15:11:48 INFO - 'sessionrestore', 15:11:48 INFO - 'sessionrestore_no_auto_restore', 15:11:48 INFO - 'tabpaint']}, 15:11:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:11:48 INFO - 'tests': ['tsvgx', 15:11:48 INFO - 'tsvgr_opacity', 15:11:48 INFO - 'tart', 15:11:48 INFO - 'tscrollx', 15:11:48 INFO - 'cart']}, 15:11:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:11:48 INFO - 'tsvgr_opacity', 15:11:48 INFO - 'tart', 15:11:48 INFO - 'tscrollx', 15:11:48 INFO - 'cart']}, 15:11:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:48 INFO - 'talos_options': ['--disable-e10s'], 15:11:48 INFO - 'tests': ['tp5o']}, 15:11:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:48 INFO - 'tests': ['tp5o']}, 15:11:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:48 INFO - 'talos_options': ['--disable-e10s', 15:11:48 INFO - '--xperf_path', 15:11:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:11:48 INFO - 'tests': ['tp5n']}, 15:11:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:48 INFO - 'talos_options': ['--xperf_path', 15:11:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:11:48 INFO - 'tests': ['tp5n']}}, 15:11:48 INFO - 'talos.zip': {'path': '', 15:11:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:11:48 INFO - Running post-action listener: _resource_record_post_action 15:11:48 INFO - [mozharness: 2017-01-14 23:11:48.988864Z] Finished populate-webroot step (success) 15:11:48 INFO - [mozharness: 2017-01-14 23:11:48.988994Z] Running create-virtualenv step. 15:11:48 INFO - Running pre-action listener: _resource_record_pre_action 15:11:48 INFO - Running main action method: create_virtualenv 15:11:48 INFO - Creating virtualenv /builds/slave/test/build/venv 15:11:48 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 15:11:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:11:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:11:49 INFO - Using real prefix '/tools/python27' 15:11:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:11:49 INFO - Installing distribute.............................................................................................................................................................................................done. 15:11:52 INFO - Installing pip.................done. 15:11:52 INFO - Return code: 0 15:11:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:11:52 INFO - Reading from file tmpfile_stdout 15:11:52 INFO - Output received: 15:11:52 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) 15:11:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10591f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105895570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae495a1760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105733d30>, '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 0x105599db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10572bb20>, '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.dW78mkqDca/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.HviZ8ix1Sk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:11:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:11:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dW78mkqDca/Render', 15:11:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:52 INFO - 'HOME': '/Users/cltbld', 15:11:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:52 INFO - 'LOGNAME': 'cltbld', 15:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:52 INFO - 'MOZ_NO_REMOTE': '1', 15:11:52 INFO - 'NO_EM_RESTART': '1', 15:11:52 INFO - 'PAGER': '/bin/cat', 15:11:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:52 INFO - 'PWD': '/builds/slave/test', 15:11:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:52 INFO - 'SHELL': '/bin/bash', 15:11:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HviZ8ix1Sk/Listeners', 15:11:52 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:11:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:52 INFO - 'USER': 'cltbld', 15:11:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:52 INFO - 'XPC_FLAGS': '0x0', 15:11:52 INFO - 'XPC_SERVICE_NAME': '0', 15:11:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:53 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 15:11:53 INFO - Cleaning up... 15:11:53 INFO - Return code: 0 15:11:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:11: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')]} 15:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11: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')]} 15:11:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11: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', '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 0x10591f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105895570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae495a1760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105733d30>, '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 0x105599db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10572bb20>, '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.dW78mkqDca/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.HviZ8ix1Sk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:11: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', 'psutil>=3.1.1'] in /builds/slave/test/build 15:11: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 psutil>=3.1.1 15:11:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dW78mkqDca/Render', 15:11:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:53 INFO - 'HOME': '/Users/cltbld', 15:11:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:53 INFO - 'LOGNAME': 'cltbld', 15:11:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:53 INFO - 'MOZ_NO_REMOTE': '1', 15:11:53 INFO - 'NO_EM_RESTART': '1', 15:11:53 INFO - 'PAGER': '/bin/cat', 15:11:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:53 INFO - 'PWD': '/builds/slave/test', 15:11:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:53 INFO - 'SHELL': '/bin/bash', 15:11:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HviZ8ix1Sk/Listeners', 15:11:53 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:11:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:53 INFO - 'USER': 'cltbld', 15:11:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:53 INFO - 'XPC_FLAGS': '0x0', 15:11:53 INFO - 'XPC_SERVICE_NAME': '0', 15:11:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:53 INFO - Downloading/unpacking psutil>=3.1.1 15:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:11:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:11:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:11:57 INFO - Installing collected packages: psutil 15:11:57 INFO - Running setup.py install for psutil 15:11:57 INFO - building 'psutil._psutil_osx' extension 15:11:57 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 15:11:58 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 15:11:58 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 15:11:58 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 15:11:58 INFO - building 'psutil._psutil_posix' extension 15:11:58 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 15:11:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:11:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:11:58 INFO - ^ 15:11:58 INFO - 1 warning generated. 15:11:58 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 15:11:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:11:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:11:58 INFO - Successfully installed psutil 15:11:58 INFO - Cleaning up... 15:11:58 INFO - Return code: 0 15:11:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10591f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105895570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae495a1760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105733d30>, '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 0x105599db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10572bb20>, '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.dW78mkqDca/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.HviZ8ix1Sk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:11:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:11:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:11:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dW78mkqDca/Render', 15:11:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:58 INFO - 'HOME': '/Users/cltbld', 15:11:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:58 INFO - 'LOGNAME': 'cltbld', 15:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:58 INFO - 'MOZ_NO_REMOTE': '1', 15:11:58 INFO - 'NO_EM_RESTART': '1', 15:11:58 INFO - 'PAGER': '/bin/cat', 15:11:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:58 INFO - 'PWD': '/builds/slave/test', 15:11:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:58 INFO - 'SHELL': '/bin/bash', 15:11:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HviZ8ix1Sk/Listeners', 15:11:58 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:11:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:58 INFO - 'USER': 'cltbld', 15:11:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:58 INFO - 'XPC_FLAGS': '0x0', 15:11:58 INFO - 'XPC_SERVICE_NAME': '0', 15:11:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:12:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:12:01 INFO - Installing collected packages: mozsystemmonitor 15:12:01 INFO - Running setup.py install for mozsystemmonitor 15:12:01 INFO - Successfully installed mozsystemmonitor 15:12:01 INFO - Cleaning up... 15:12:02 INFO - Return code: 0 15:12:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:02 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 0x10591f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105895570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae495a1760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105733d30>, '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 0x105599db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10572bb20>, '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.dW78mkqDca/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.HviZ8ix1Sk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:12:02 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 15:12:02 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 15:12:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dW78mkqDca/Render', 15:12:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:02 INFO - 'HOME': '/Users/cltbld', 15:12:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:02 INFO - 'LOGNAME': 'cltbld', 15:12:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:02 INFO - 'MOZ_NO_REMOTE': '1', 15:12:02 INFO - 'NO_EM_RESTART': '1', 15:12:02 INFO - 'PAGER': '/bin/cat', 15:12:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:02 INFO - 'PWD': '/builds/slave/test', 15:12:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:02 INFO - 'SHELL': '/bin/bash', 15:12:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HviZ8ix1Sk/Listeners', 15:12:02 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:12:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:02 INFO - 'USER': 'cltbld', 15:12:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:02 INFO - 'XPC_FLAGS': '0x0', 15:12:02 INFO - 'XPC_SERVICE_NAME': '0', 15:12:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:02 INFO - Downloading/unpacking jsonschema==2.5.1 15:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:12:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:12:04 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 15:12:04 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 15:12:04 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 15:12:04 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 15:12:04 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 15:12:04 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 15:12:05 INFO - Downloading functools32-3.2.3-2.tar.gz 15:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:12:05 INFO - warning: no files found matching '*.txt' 15:12:05 INFO - no previously-included directories found matching 'build' 15:12:05 INFO - no previously-included directories found matching 'dist' 15:12:05 INFO - no previously-included directories found matching '.git*' 15:12:05 INFO - Installing collected packages: jsonschema, functools32 15:12:05 INFO - Running setup.py install for functools32 15:12:05 INFO - warning: no files found matching '*.txt' 15:12:05 INFO - no previously-included directories found matching 'build' 15:12:05 INFO - no previously-included directories found matching 'dist' 15:12:05 INFO - no previously-included directories found matching '.git*' 15:12:05 INFO - Successfully installed jsonschema functools32 15:12:05 INFO - Cleaning up... 15:12:05 INFO - Return code: 0 15:12:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:12:05 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')]} 15:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:05 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')]} 15:12:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:05 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 0x10591f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105895570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae495a1760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105733d30>, '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 0x105599db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10572bb20>, '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.dW78mkqDca/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.HviZ8ix1Sk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:12:05 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 15:12:05 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 15:12:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dW78mkqDca/Render', 15:12:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:05 INFO - 'HOME': '/Users/cltbld', 15:12:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:05 INFO - 'LOGNAME': 'cltbld', 15:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:05 INFO - 'MOZ_NO_REMOTE': '1', 15:12:05 INFO - 'NO_EM_RESTART': '1', 15:12:05 INFO - 'PAGER': '/bin/cat', 15:12:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:05 INFO - 'PWD': '/builds/slave/test', 15:12:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:05 INFO - 'SHELL': '/bin/bash', 15:12:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HviZ8ix1Sk/Listeners', 15:12:05 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:12:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:05 INFO - 'USER': 'cltbld', 15:12:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:05 INFO - 'XPC_FLAGS': '0x0', 15:12:05 INFO - 'XPC_SERVICE_NAME': '0', 15:12:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:12:05 INFO - Cleaning up... 15:12:05 INFO - Return code: 0 15:12:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:12:05 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')]} 15:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:05 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')]} 15:12:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:05 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 0x10591f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105895570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae495a1760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105733d30>, '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 0x105599db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10572bb20>, '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.dW78mkqDca/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.HviZ8ix1Sk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:12:05 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 15:12:05 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 15:12:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dW78mkqDca/Render', 15:12:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:05 INFO - 'HOME': '/Users/cltbld', 15:12:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:05 INFO - 'LOGNAME': 'cltbld', 15:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:05 INFO - 'MOZ_NO_REMOTE': '1', 15:12:05 INFO - 'NO_EM_RESTART': '1', 15:12:05 INFO - 'PAGER': '/bin/cat', 15:12:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:05 INFO - 'PWD': '/builds/slave/test', 15:12:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:05 INFO - 'SHELL': '/bin/bash', 15:12:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HviZ8ix1Sk/Listeners', 15:12:05 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:12:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:05 INFO - 'USER': 'cltbld', 15:12:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:05 INFO - 'XPC_FLAGS': '0x0', 15:12:05 INFO - 'XPC_SERVICE_NAME': '0', 15:12:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:06 INFO - Downloading/unpacking blobuploader==1.2.4 15:12:06 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 15:12:06 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 15:12:06 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 15:12:06 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 15:12:06 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 15:12:06 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 15:12:08 INFO - Downloading blobuploader-1.2.4.tar.gz 15:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:12:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:12:08 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 15:12:08 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 15:12:08 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 15:12:08 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 15:12:08 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 15:12:08 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 15:12:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:12:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:09 INFO - Downloading docopt-0.6.1.tar.gz 15:12:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:12:09 INFO - Installing collected packages: blobuploader, requests, docopt 15:12:09 INFO - Running setup.py install for blobuploader 15:12:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:12:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:12:10 INFO - Running setup.py install for requests 15:12:10 INFO - Running setup.py install for docopt 15:12:10 INFO - Successfully installed blobuploader requests docopt 15:12:10 INFO - Cleaning up... 15:12:10 INFO - Return code: 0 15:12:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:12:10 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')]} 15:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:10 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')]} 15:12:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:10 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 0x10591f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105895570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae495a1760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105733d30>, '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 0x105599db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10572bb20>, '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.dW78mkqDca/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.HviZ8ix1Sk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:12:10 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 15:12:10 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 15:12:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dW78mkqDca/Render', 15:12:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:10 INFO - 'HOME': '/Users/cltbld', 15:12:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:10 INFO - 'LOGNAME': 'cltbld', 15:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:10 INFO - 'MOZ_NO_REMOTE': '1', 15:12:10 INFO - 'NO_EM_RESTART': '1', 15:12:10 INFO - 'PAGER': '/bin/cat', 15:12:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:10 INFO - 'PWD': '/builds/slave/test', 15:12:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:10 INFO - 'SHELL': '/bin/bash', 15:12:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HviZ8ix1Sk/Listeners', 15:12:10 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:12:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:10 INFO - 'USER': 'cltbld', 15:12:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:10 INFO - 'XPC_FLAGS': '0x0', 15:12:10 INFO - 'XPC_SERVICE_NAME': '0', 15:12:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:12:11 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-S07xaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:12:11 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Xr6uqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:12:11 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-eoRnK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:12:11 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Hds4fI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:12:11 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-BPQpRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:12:11 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-n2bziX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:12:11 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-OJJ3oo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:12:11 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-XAfCvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:12:11 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-xoOxP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:12:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-uhsb24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:12:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-iTEAF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:12:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-yNq09S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:12:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-hzVQLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:12:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-EBLMyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-F1QfU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:12:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-_RYrDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:12:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-meutSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:12:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:12:12 INFO - Running setup.py install for manifestparser 15:12:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:12:13 INFO - Running setup.py install for mozcrash 15:12:13 INFO - Running setup.py install for mozdebug 15:12:13 INFO - Running setup.py install for mozdevice 15:12:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:12:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:12:13 INFO - Running setup.py install for mozfile 15:12:13 INFO - Running setup.py install for mozhttpd 15:12:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:12:13 INFO - Running setup.py install for mozinfo 15:12:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:12:13 INFO - Running setup.py install for mozInstall 15:12:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:12:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:12:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:12:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:12:14 INFO - Running setup.py install for mozleak 15:12:14 INFO - Running setup.py install for mozlog 15:12:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:12:14 INFO - Running setup.py install for moznetwork 15:12:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:12:14 INFO - Running setup.py install for mozprocess 15:12:14 INFO - Running setup.py install for mozprofile 15:12:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:12:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:12:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:12:14 INFO - Running setup.py install for mozrunner 15:12:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:12:15 INFO - Running setup.py install for mozscreenshot 15:12:15 INFO - Running setup.py install for moztest 15:12:15 INFO - Running setup.py install for mozversion 15:12:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:12:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:12:15 INFO - Cleaning up... 15:12:15 INFO - Return code: 0 15:12:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:12:15 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')]} 15:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:15 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')]} 15:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:15 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 0x10591f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105895570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae495a1760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105733d30>, '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 0x105599db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10572bb20>, '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.dW78mkqDca/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.HviZ8ix1Sk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:12:15 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 15:12:15 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 15:12:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dW78mkqDca/Render', 15:12:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:15 INFO - 'HOME': '/Users/cltbld', 15:12:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:15 INFO - 'LOGNAME': 'cltbld', 15:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:15 INFO - 'MOZ_NO_REMOTE': '1', 15:12:15 INFO - 'NO_EM_RESTART': '1', 15:12:15 INFO - 'PAGER': '/bin/cat', 15:12:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:15 INFO - 'PWD': '/builds/slave/test', 15:12:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:15 INFO - 'SHELL': '/bin/bash', 15:12:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HviZ8ix1Sk/Listeners', 15:12:15 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:12:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:15 INFO - 'USER': 'cltbld', 15:12:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:15 INFO - 'XPC_FLAGS': '0x0', 15:12:15 INFO - 'XPC_SERVICE_NAME': '0', 15:12:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:12:15 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-JR1PUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:12:15 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)) 15:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:12:15 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-oeD7WJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:12:15 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)) 15:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:12:15 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-6vrEuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:12:16 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)) 15:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:12:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-DTDvBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:12:16 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)) 15:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:12:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-20Ho9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:12:16 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)) 15:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:12:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-kfl3Xl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:12:16 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)) 15:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:12:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-WaJDmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:12:16 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)) 15:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:12:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-laHl5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:12:16 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)) 15:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:12:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-qmFkfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:12:16 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)) 15:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:12:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-h1odlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:12:16 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)) 15:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:12:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-lXFdKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:12:16 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)) 15:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:12:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-5b3ZWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:12:17 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)) 15:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:12:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-fB2q1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:12:17 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)) 15:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:12:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-xEvYKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:12:17 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)) 15:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-6pagdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:17 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)) 15:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:12:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-JkfV8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:12:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:12:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-PrlROO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:12:17 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)) 15:12:17 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)) 15:12:17 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)) 15:12:17 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)) 15:12:17 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)) 15:12:17 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)) 15:12:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:12:17 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 15:12:17 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 15:12:17 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 15:12:17 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 15:12:17 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 15:12:17 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 15:12:20 INFO - Downloading blessings-1.6.tar.gz 15:12:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:12:20 INFO - Installing collected packages: blessings 15:12:20 INFO - Running setup.py install for blessings 15:12:20 INFO - Successfully installed blessings 15:12:20 INFO - Cleaning up... 15:12:20 INFO - Return code: 0 15:12:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:12:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:12:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:12:20 INFO - Reading from file tmpfile_stdout 15:12:20 INFO - Current package versions: 15:12:20 INFO - blessings == 1.6 15:12:20 INFO - blobuploader == 1.2.4 15:12:20 INFO - docopt == 0.6.1 15:12:20 INFO - functools32 == 3.2.3-2 15:12:20 INFO - jsonschema == 2.5.1 15:12:20 INFO - manifestparser == 1.1 15:12:20 INFO - mozInstall == 1.12 15:12:20 INFO - mozcrash == 1.0 15:12:20 INFO - mozdebug == 0.1 15:12:20 INFO - mozdevice == 0.48 15:12:20 INFO - mozfile == 1.2 15:12:20 INFO - mozhttpd == 0.7 15:12:20 INFO - mozinfo == 0.9 15:12:20 INFO - mozleak == 0.1 15:12:20 INFO - mozlog == 3.4 15:12:20 INFO - moznetwork == 0.27 15:12:20 INFO - mozprocess == 0.23 15:12:20 INFO - mozprofile == 0.28 15:12:20 INFO - mozrunner == 6.13 15:12:20 INFO - mozscreenshot == 0.1 15:12:20 INFO - mozsystemmonitor == 0.3 15:12:20 INFO - moztest == 0.8 15:12:20 INFO - mozversion == 1.4 15:12:20 INFO - psutil == 3.1.1 15:12:20 INFO - requests == 1.2.3 15:12:20 INFO - wsgiref == 0.1.2 15:12:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:12:20 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')]} 15:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:20 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')]} 15:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:20 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 0x10591f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105895570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae495a1760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105733d30>, '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 0x105599db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10572bb20>, '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.dW78mkqDca/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.HviZ8ix1Sk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:12:20 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 15:12:20 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 15:12:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dW78mkqDca/Render', 15:12:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:20 INFO - 'HOME': '/Users/cltbld', 15:12:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:20 INFO - 'LOGNAME': 'cltbld', 15:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:20 INFO - 'MOZ_NO_REMOTE': '1', 15:12:20 INFO - 'NO_EM_RESTART': '1', 15:12:20 INFO - 'PAGER': '/bin/cat', 15:12:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:20 INFO - 'PWD': '/builds/slave/test', 15:12:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:20 INFO - 'SHELL': '/bin/bash', 15:12:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HviZ8ix1Sk/Listeners', 15:12:20 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:12:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:20 INFO - 'USER': 'cltbld', 15:12:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:20 INFO - 'XPC_FLAGS': '0x0', 15:12:20 INFO - 'XPC_SERVICE_NAME': '0', 15:12:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:21 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)) 15:12:21 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)) 15:12:21 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)) 15:12:21 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)) 15:12:21 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)) 15:12:21 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)) 15:12:21 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)) 15:12:21 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)) 15:12:21 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)) 15:12:21 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)) 15:12:21 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)) 15:12:21 INFO - Cleaning up... 15:12:21 INFO - Return code: 0 15:12:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:12:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:21 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 0x10591f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105895570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae495a1760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105733d30>, '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 0x105599db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10572bb20>, '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.dW78mkqDca/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.HviZ8ix1Sk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:12:21 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 15:12:21 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 15:12:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dW78mkqDca/Render', 15:12:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:21 INFO - 'HOME': '/Users/cltbld', 15:12:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:21 INFO - 'LOGNAME': 'cltbld', 15:12:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:21 INFO - 'MOZ_NO_REMOTE': '1', 15:12:21 INFO - 'NO_EM_RESTART': '1', 15:12:21 INFO - 'PAGER': '/bin/cat', 15:12:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:21 INFO - 'PWD': '/builds/slave/test', 15:12:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:21 INFO - 'SHELL': '/bin/bash', 15:12:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HviZ8ix1Sk/Listeners', 15:12:21 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:12:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:21 INFO - 'USER': 'cltbld', 15:12:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:21 INFO - 'XPC_FLAGS': '0x0', 15:12:21 INFO - 'XPC_SERVICE_NAME': '0', 15:12:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:12:21 INFO - Cleaning up... 15:12:21 INFO - Return code: 0 15:12:21 INFO - Running post-action listener: _resource_record_post_action 15:12:21 INFO - Running post-action listener: _start_resource_monitoring 15:12:21 INFO - Starting resource monitoring. 15:12:21 INFO - [mozharness: 2017-01-14 23:12:21.532249Z] Finished create-virtualenv step (success) 15:12:21 INFO - [mozharness: 2017-01-14 23:12:21.533213Z] Running install step. 15:12:21 INFO - Running pre-action listener: _resource_record_pre_action 15:12:21 INFO - Running main action method: install 15:12:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:12:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:12:21 INFO - Reading from file tmpfile_stdout 15:12:21 INFO - Detecting whether we're running mozinstall >=1.0... 15:12:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:12:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:12:21 INFO - Reading from file tmpfile_stdout 15:12:21 INFO - Output received: 15:12:21 INFO - Usage: mozinstall [options] installer 15:12:21 INFO - Options: 15:12:21 INFO - -h, --help show this help message and exit 15:12:21 INFO - -d DEST, --destination=DEST 15:12:21 INFO - Directory to install application into. [default: 15:12:21 INFO - "/builds/slave/test"] 15:12:21 INFO - --app=APP Application being installed. [default: firefox] 15:12:21 INFO - mkdir: /builds/slave/test/build/application 15:12:21 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'] 15:12:21 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 15:12:52 INFO - Reading from file tmpfile_stdout 15:12:52 INFO - Output received: 15:12:52 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:12:52 INFO - Running post-action listener: _resource_record_post_action 15:12:52 INFO - [mozharness: 2017-01-14 23:12:52.999874Z] Finished install step (success) 15:12:53 INFO - [mozharness: 2017-01-14 23:12:53.000012Z] Running run-tests step. 15:12:53 INFO - Running pre-action listener: _resource_record_pre_action 15:12:53 INFO - Running main action method: run_tests 15:12:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:12:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:12:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:12:53 INFO - 2017-01-14 15:12:53.014 screenresolution[1640:12349] starting screenresolution argv=screenresolution get 15:12:53 INFO - 2017-01-14 15:12:53.017 screenresolution[1640:12349] Display 0: 1600x1200x32@60 15:12:53 INFO - 2017-01-14 15:12:53.023 screenresolution[1641:12351] starting screenresolution argv=screenresolution list 15:12:53 INFO - Available Modes on Display 0 15:12:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:12:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:12:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:12:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:12:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:12:53 INFO - Intel Iris: 15:12:53 INFO - Chipset Model: Intel Iris 15:12:53 INFO - Type: GPU 15:12:53 INFO - Bus: Built-In 15:12:53 INFO - VRAM (Dynamic, Max): 1536 MB 15:12:53 INFO - Vendor: Intel (0x8086) 15:12:53 INFO - Device ID: 0x0a2e 15:12:53 INFO - Revision ID: 0x0009 15:12:53 INFO - Displays: 15:12:53 INFO - Display: 15:12:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:12:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:12:53 INFO - Display Serial Number: 1600x1200 60 15:12:53 INFO - Main Display: Yes 15:12:53 INFO - Mirror: Off 15:12:53 INFO - Online: Yes 15:12:53 INFO - Rotation: Supported 15:12:53 INFO - Return code: 0 15:12:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:12:53 INFO - Python 2.7.3 15:12:53 INFO - Return code: 0 15:12:53 INFO - grabbing minidump binary from tooltool 15:12: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')]} 15:12:53 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 0x105733d30>, '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 0x105599db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10572bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:12:53 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 15:12:53 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 15:12:53 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 15:12:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:12:53 INFO - Return code: 0 15:12:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:12:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:12:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:12:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:12:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:12:53 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/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--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 15:12:53 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/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0173 --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 15:12:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dW78mkqDca/Render', 15:12:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:53 INFO - 'HOME': '/Users/cltbld', 15:12:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:53 INFO - 'LOGNAME': 'cltbld', 15:12:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:12:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:53 INFO - 'MOZ_NO_REMOTE': '1', 15:12:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:12:53 INFO - 'NO_EM_RESTART': '1', 15:12:53 INFO - 'PAGER': '/bin/cat', 15:12:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:53 INFO - 'PWD': '/builds/slave/test', 15:12:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:12:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:53 INFO - 'SHELL': '/bin/bash', 15:12:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HviZ8ix1Sk/Listeners', 15:12:53 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:12:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:53 INFO - 'USER': 'cltbld', 15:12:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:53 INFO - 'XPC_FLAGS': '0x0', 15:12:53 INFO - 'XPC_SERVICE_NAME': '0', 15:12:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:53 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/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--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 15:12:53 INFO - mozversion application_buildid: 20170114140724 15:12:53 INFO - mozversion application_changeset: 1e04cc4582dd86864035f0aed5fac81907dea848 15:12:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:12:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:12:53 INFO - mozversion application_name: Firefox 15:12:53 INFO - mozversion application_remotingname: firefox-dev 15:12:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:12:53 INFO - mozversion application_vendor: Mozilla 15:12:53 INFO - mozversion application_version: 52.0a2 15:12:53 INFO - mozversion platform_buildid: 20170114140724 15:12:53 INFO - mozversion platform_changeset: 1e04cc4582dd86864035f0aed5fac81907dea848 15:12:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:12:53 INFO - mozversion platform_version: 52.0a2 15:12:53 INFO - using testdate: 1484435573 15:12:53 INFO - actual date: 1484435573 15:12:53 INFO - starting webserver on 'localhost:49240' 15:12:53 INFO - SUITE-START | Running 2 tests 15:12:53 INFO - TEST-START | dromaeo_css 15:12:53 INFO - operating with platform_type : mac_ 15:12:53 INFO - Initialising browser for dromaeo_css test... 15:12:53 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpIyOIkI/profile http://localhost:49240/getInfo.html) 15:12:56 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 15:12:56 INFO - PROCESS | 1651 | colorDepth:24 15:12:56 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 15:12:56 INFO - PROCESS | 1651 | __metrics 15:12:56 INFO - PROCESS | 1651 | 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" 15:12:56 INFO - PROCESS | 1651 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:12:57 INFO - TEST-INFO | 1651: exit 0 15:12:57 INFO - Browser initialized. 15:12:57 INFO - Running cycle 1/1 for dromaeo_css test... 15:12:57 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpIyOIkI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:13:09 INFO - PROCESS | 1654 | RSS: Main: 221925376 15:13:09 INFO - PROCESS | 1654 | 15:16:13 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 15:16:15 INFO - PROCESS | 1654 | RSS: Main: 371392512 15:16:15 INFO - PROCESS | 1654 | 15:19:18 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 15:19:18 INFO - PROCESS | 1654 | RSS: Main: 348983296 15:19:18 INFO - PROCESS | 1654 | 15:22:21 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 15:22:21 INFO - PROCESS | 1654 | RSS: Main: 325611520 15:22:21 INFO - PROCESS | 1654 | 15:25:23 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 15:25:24 INFO - PROCESS | 1654 | RSS: Main: 322928640 15:25:24 INFO - PROCESS | 1654 | 15:28:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 15:28:28 INFO - PROCESS | 1654 | RSS: Main: 307154944 15:28:28 INFO - PROCESS | 1654 | 15:31:31 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 15:31:31 INFO - PROCESS | 1654 | RSS: Main: 356454400 15:31:31 INFO - PROCESS | 1654 | 15:31:31 INFO - PROCESS | 1654 | __start_tp_report 15:31:31 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 15:31:31 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 15:31:31 INFO - PROCESS | 1654 | |i|pagename|runs| 15:31:31 INFO - PROCESS | 1654 | |0;dojo.html;3328.818269514321;3353.2958078803445;3355.6141043779967;3377.273886114485;3381.932361352773;5704.800331988381;5724.856878578033;5779.537636989041;5800.825975220745;5826;5739.713014349287;5814.534837213024;5836.2996440427105;5836.32882218545;5839.824805255849;5255.316808814849;5256.842294731164;5295.523402893738;5324.494173053554;5342.866428339284;4260.42484264625;5234;5237.162054071349;5241.633085684004;5364.865878353034;5338.386085600147;5348.277982472356;5352.411234764173;5356.91964620531;5366.758495867695;5345.1982202669715;5353.330833645794;5354.303940487751;5356.330458692663;5413.188021796723;5134.922976155361;5155.510226528474;5194.584433245369;5285.101532739405;5305.124654432014;5655.8586035349035;5671.177679236487;5694.487496125327;5749.367569567345;5757.222774925373;6216.254049514042;6229.719662615148;6273.749050037993;6278.968605156945;6287.308396076428;6254.906176407358;6388.680565971729;6409.871802563923;6411.647359395231;6423.582467139621;5691.231683722727;5726.455986681259;5735.541156707453;5814.79648212319;5929.021711417654;6297.1813664223355;6331.588446750947;6351;6363;6370.0763389308295;6208.875822483525;6222.688865556704;6232.034034724625;6236.158118654014;6241;6117.2582339092005;6206.689665516707;6276.152719382825;6321.273053598803;6330.715117819617;6297.937020629826;6311.873762524724;6330.936690633035;6331.810045698637;6336.239651241881;6265.561410701207;6301.842453938597;6344.238691357066;6349;6356.904646430307;4843.975780121147;4884.389451318585;4960.330355401975;4970.403551573762;4980.626453015966;4442.800073996613;4452.532484089189;4481.1933851906315;4487.551244875552;4499.055198408306;5839.299284085937;5848.9707551462825;5854.795082172103;5866.384029676908;5868.38381969887;5270.552003079782;6373.745050198033;6375.075614036032;6398.200224971879;6407;5592.692401917933;5681.0910254359105;5697.772089116472;5755.251817263813;5783;5389.110796718576;5396.244525766396;5420.647657902303;5436.864078397993;5440.809571664972;5944.286685597756;5971.104334349882;6334.018227174795;6342.143810585512;6345.079963405373;4663.533646635309;6254.21822272216;6310.652914089769;6351.460125889351;6371.23545174582;6291.653959032297;6305.747770089145;6305.90541141874;6309.148264984352;6370.904436433365;6239.500839932885;6248.718807653758;6273.498120150286;6290.0879372491645;6297.433231009231;6164.568480206342;6211.937880621136;6217.4715149212825;6227.252729672468;6263;5155.9742201288755;5828.970855145867;6091.7563297467605;6114.633122012693;6141.087130295657;6153.692315384303;6211.720472578835;6225.844353891189;6226.657533835682;6233.345498722751;6061.121137435135;6066.908996365147;6075.969620151871;6076.149339092619;6084.209052823282;6045.727942242522;6122.173506576733;6134.773364742421;6168.35232300602;6187.752489900354;6002.429769172097;6073.210482637405;6106.542009349228;6127.754889804358;6136 15:31:31 INFO - PROCESS | 1654 | |1;ext.html;458.44757067733383;460.22452168096737;526.807715183958;529.6477842234914;535.7356638333534;29195;37884.62115378845;38888.027799305;38972.80513597428;39028.60971390285;39972.600273997254;40254.597454025454;40335;40350.39474407879;40377.798111009404;9099.454032758023;9249.075092490764;9255.814883702322;9257.722268331961;9263.351565390425;9073.274138068939;9153.63385464584;9155.72532824013;9165.495897725623;9175.128362805523;9141.268698504136;9171.63313467464;9177.587008584629;9183.632654693803;9194.77013074675;7293.416526677853;9110.908890911072;9148.4968326742;9162.267018638493;9175.77060573491;9122.498262595554;9193.448393096436;9198.540072996324;9201.769955751093;9206.63173473067;31161.90933317323;31240.687593124232;33789.98630041103;35595.28809423797;37691.49234030635;3109.2226943264186;3192.6009248843893;3245.302260014085;3421.007907706762;3563.893083207508;28441.71558284432;28462.573061404095;28898.855505722546;29244.41511169786;29505.852470737722;2990.4617168909585;3100.8449577521033;3112.766542509321;3114.5328200770064;3116.812991220534;507893;510656;511514.88485115045;512004;525070.3746481282;31681.84159079236;58097.128543071885;58608.534786629825;58760.41239587549;58872;32659.673403266137;33518.49722254168;33859.492107618404;33864;33908;13960.162390256617;14036;14052.929735351463;14056.367463464168;14063.85936140646;11882;11944.402779861146;11947.581834635746;11949.10381721357;11958.103142264365;7562.924370756222;7566.205548417337;7648.694052237959;7653;7673.079230492377;2852.0160544612077;3166.4142133705336;3190.4257233697695;3207.7273431758094;3217.420864244412;901.733988473402;937.3110763588757;943.6602822983721;995.716220877061;1008.3161120927921;22444.66333005006;28246.85876570604;28752.712472875002;28940.42119157647;29103.708962910525;1024.7694268789462;1025.5026312238558;1043.4574021508772;1087.7335052911928;1177.7232350397649;14345.354459049368;14391.928040359731;14769.261536923326;14966.401343946338;15070.0204486708;628494;633069;635427;635726;636064;45294;54426.45573544293;54947;55026.724866375414;55042.17436738452;1097.9835302470628;1201.2072032458536;1252.993735031319;1353.3301015997145;1375.532319011541;25011.749882500942;29049.85475072611;30312.242193945327;30822.69177308198;31053.864870671496;50530.74734626451;51066;51256.23115653193;51269;51439.742801285756;535299;540355;540944;542399;544496;22187.77812221857;22758.86205689742;25246.242612720915;25651.358716031504;25694.972201111745;44144;44383.77808110939;44912.10175796531;44956.87607809831;46495.07009859716;37127.62872371242;37774.43338349872;38256.23487530178;39518;39546.60453395429;36548.63451365559;36642.45036324404;37787;37921.62078379181;37957;35978.8201058993;36485.45271820872;36673.81663091668;36955.81522092372;37057.25885482329;31593.8420307897;33131.337373252885;33368.832090876705;33393.99818005464;33411.33177336488;20936.58126837485;24777.752222477546;24799.380015499755;24812.875935620206;25191.74808251894 15:31:31 INFO - PROCESS | 1654 | |2;jquery.html;347.46316940327193;366.10669965284706;371.10563541864116;375.21579898013147;397.9522457305124;14508.564743057712;14692.559223223307;15765.763513547307;19909.00454977249;20379.898100509512;16522.42171523997;19625.509362265915;20091.899540502312;20288.89855550724;20473.488162795937;5765.7981970631035;6056.21269235001;6062.939370606304;6064.090386442025;6068.8179354619215;5333.146696528556;5794.855128621776;5955.868385006835;6029.788957386492;6038.51691864652;5698.971505142468;5953.166556682068;5954.434328738785;5958.255218097738;5958.344582095966;5868.823935281927;5990.820275391745;5993.370696076893;5994.340622531518;5994.850128746794;5049.166887463564;5237.633365664405;5243.580513558906;5252.6323157379;5336.38631557372;12777.29724865132;12933.805992910115;13113.737725245537;13138.474461021548;13146.60560183196;2131.360591822447;2205.5809396214827;2250.437390652337;2269.239804665439;2299.620562607168;9551.331406801526;10090.848637270374;10170.135538479239;13046.869531304661;13718.314084295745;1800.522861441719;1807.2951548895942;1829.9176537055864;1875.3623767918837;1903.5050886769402;92939;99481.0051899479;104802.42796358137;124679;130192;9430.669926552675;10460.895391045991;10502.369857808557;11571.6528504145;11703.23928944616;14098.929505352613;14219.004669673024;14279.500217492545;14436.855631443761;14560.980731348704;8328.08391076981;8390.244877960891;8460.619272132768;8560.229579337889;8567.74296771098;8107.675692972215;8285;8335.705972557224;8413.957930210432;8475.872861907077;4417.204903117406;4524.140413321458;4551.408316918845;4554.521775213555;4568.223402021597;1859.7675290588677;1880.9059547022594;1884.1427150646423;1887.981120188808;1920.5116035072651;828.3911325175991;852.394799692213;900.9099090090939;918.6187732091241;1026.8870424253325;17683.73474397885;21087.578248434944;21342.93285335727;21635.242766503095;21722.78277217239;910.8497097978892;942.9575669094908;949.5964215208647;951.8952915179325;978.395431492353;9469.289803264783;10315.27793054494;11087.72280692989;11114;11135.33188008722;520690;522009;524279;559920;596986;96942;113460;114183;114205;118272;934.0845970948509;944.1408318430058;987.7234374375187;988.7577543501889;989.2333441582687;18621.975791331064;19226.423207303244;19444.902775486033;19522.023898805288;19537.51156221049;52804.20793688021;53447;53455.73272133614;54768;55244.72377638086;79581;80041;80588.19411806042;82370;82484;5477.096279113902;6658;6859.519833611599;7851.05787305527;8289;11431.256968297366;12151.027917766382;12253.816192757295;12583.370831458207;12621.305828179538;9898.15865651399;10685.252032357661;10789.40658263803;10982.231243813176;11137.665870023913;10659.573617055232;10665.733356666145;10678.519466623866;10917.344959302467;11278.266912650652;10911.544902396898;11035.448227588427;11402.144839137263;11409.657710268706;11497.885021149681;7913.485623434458;8134.959325203336;8137.471064380797;8150.470219435955;8150.673973041012;9696.660616878375;10292.382457052601;10401.84397234027;10572.524236409226;11220.326780393203 15:31:31 INFO - PROCESS | 1654 | |3;mootools.html;1463.0855715178013;1489.3967942983093;1632.1757512456209;1763.735439684048;1807.764990551229;7940.325072368848;8298.792530186749;8322.250997410232;8326;8336.624851881672;7377.926220737791;7610.54336739796;7619.238076192378;8008.879866801988;8115.91884081159;2278.5670722562745;2317.8377513574055;2320.3155069254612;2320.385097949045;2326.8254880883915;2220.9222677206303;2253.3803204118917;2258.7402448718444;2259.9435014124615;2271.1256166375897;2291.702078729763;2297.2419101696482;2299.8505097168713;2301.827362947777;2327.2320134355623;2283.6003699352714;2288.645259984705;2314.3982564533258;2314.8611083335054;2317.7566355532595;2307.9538409231723;2339.251439539348;2341.1571834139695;2342.8125749939963;2345.7185137783404;8220.38347123968;8253.33973282142;8322.66709331626;8339.708110216114;8383.077861435237;5014.824481143142;5178.223266510016;5189.42916279209;5190.766415511273;5210.895782084337;2972.5689774982725;3220.339830334776;3253.9511907321407;3524.259905419865;3531.4349704047486;1042.921780866438;1046.9214808889287;1071.3946620159586;1083.8320060390652;1086.538221255971;10783.83824242637;10843.945780271048;10909.399983000932;10913.454327283684;10922.071623911977;4400.273954797487;4407.933880991788;4489.304157855538;4496.348029535699;4685.742284174403;9313.301502387349;9333.113354231336;9338.20625246848;9342.346035777566;9353.438793672402;5098.362704661918;5164.354455693038;5165.74171291434;5166.689998600096;5176.249443830623;4128.896777580585;4211.852585159505;4371.497277813029;4680.602148817321;4746.169420351424;3725.795081270502;3742.288965096677;3813.122981714221;3840.8655697050467;3862.4399462078404;1233.802591585342;1238.2261086820738;1242.5092088624942;1252.1923359433115;1253.254313683357;862.6851199312331;880.933929955256;890.5146695051083;891.9965039330752;895.3508706187962;5505.587080968943;5585.96659617972;5610.438956104357;5667.546596272288;5890.6171098879295;868.0971789339143;877.6489404238229;882.717530390269;908.895477020138;910.63404892661;3597.748157628941;3646.3254297954963;3686.262747450467;3805.733598648068;3816.31306364857;9443;9775.266854985905;9967.202623790079;10098.747531311628;10105.292629515863;10009.849852252222;10016.849747253797;10028.849567256497;10029.498525073863;10033.849492257623;7667.923320766721;7726.729564465217;7734.303912647889;7808.921910780933;7828.804279893048;5285.630005899704;5359.356877174764;5389.349241533873;5529.889402212014;5620.578456615852;9199.402038867453;9363.485008324335;9374.968753437253;9401.388909720847;9411.200047995997;9041.141091596288;9461.101195386427;9584.329096963145;9587.089226523469;9607.231421486404;6242.906356404748;6469.353064693493;6490.902636460362;6648.667566621746;6775.864482710417;7585.848283034198;7769.844603108019;7890.171531989058;7945.562994035384;7967.123616402307;6970.442364610718;7049.647517624201;7082.787516374517;7103.573785572882;7192.280771922766;6760.053592496956;6927.238003819475;6966.1988871280455;7047.189573199148;7049.29507049291;5516.503514683698;6379.972339140065;6612.933870661232;6622.63575503352;6667.733290668319;4731.597814185833;4775.522447755336;4807;4840;4862.781174847069;4704.976475117603;4713.7643117844655;4727.267273572602;4908.190148625579;4971.825986090469 15:31:31 INFO - PROCESS | 1654 | |4;prototype.html;292.39474288223374;296.8026262535414;305.08322490914804;307.80762023735167;310.67999960041135;5338.652987555806;5368.62419630626;5369.194620806871;5378.24704541364;5421.56328572928;5003.574696150831;5138.04754193406;5162.716050617215;5163.7418129093585;5267.23625074364;3445.8621655133766;3497.3529896969144;3747.9318394257643;4035.7982100894983;4047.0084829216885;3634.745567810254;3980.9848488635503;4055.722447429065;4061.3095773718537;4064.7154699170924;3720.274546463441;3978.6817054635703;3993.740406873544;3999.3201155803436;4019.939700904474;3298.917527061819;3926.055458406195;3951.308521008812;3965.9490235087724;3993.700472464562;2996.550517422404;3425.400554902882;3447.4311738563106;3452.7583069185166;3458.2564748578925;4588;4989.476105008958;5146.819861304836;5159.251908473288;5192.376914770214;3895.5714871364125;4532.9320060199125;4534.6372290216705;4542.432195975503;4553.248713962193;4947.505249475023;5153.994970980661;5501.504864562208;5523.4200408956885;5534.557235421197;3772.4529943158086;3775.1505911170043;3785.5268091488565;3787.5265591801026;3811.5606012151966;5238.973805130988;5473.589480788957;5795;5807.245058142415;5808.88382232353;3352.26250224955;3794.582595914447;3804.784956711574;3839.9616003839606;3859.4982652255026;3717.1636381814415;3893.4354518594646;3898.5516665583255;3905.2384784967226;3925.4111883217174;3923.2349691809823;4407.735535867858;4414.426124603781;4531.18438681034;4576.359309696645;4487.967767413513;5051.444341122474;5583.8883222336135;5586.91619625706;5595.832125036255;1107.1751545098791;1128.3963079752293;1193.6478738772082;1209.3650833312406;1221.712897469094;702.8945658151216;727.5998200989541;799.7840583042662;803.9155888631609;817.5789468423767;4045.4336392905016;4640.512746161605;5251.291075704808;5257.373511310023;5270.973645131749;4775.2359622460235;5052.545270925634;5617.269754931833;5717.485426311652;5743.812184237705;5019.974900125476;5190.677966101698;5228.660137091077;5234.2672025915645;5248.002879452892;2694.85468675812;2741.4105967216747;2832.844193569332;2845.7438830505353;2856.557233628791;4945.703257804544;5070.898582028339;5114.693118412907;5246.396664383568;5381.174883186359;4898.851940814256;5357.223202635674;5528.834134975958;5569.443055694398;5637.7181140942785;4700.576948074689;5006.574441172541;5118.232265160255;5531.585131115183;5659.858503537444;4640.303954406866;5038.505939559941;5342.439043900335;5408.053590621704;5574.554035677217;4959.710475276526;5739.8565035874435;5741.741621626953;5745.023346031101;5751.396103409249;4878.512148785093;5944.494717949023;5986.790462333797;6054;6064.575479716377;2966.6736658967066;3227;3281.7374610031616;3854.4411060396646;3879.5732469427776;4761.500042495627;5736.971315143398;5764;5764.452377024176;5773.769049237983;4647.7443740593235;4651.325557794168;5650.1072140833285;5683.8863222736145;5726.709316273524;4625.537446255347;5399.514043736082;5547.223388725501;5590.720463976866;5600.439956004367;4697.624190064718;4917.6311776616185;5215.6609820361555;5235.246192525547;5481.260029896072;5023.648344615842;5488.588355873246;5524.254225679482;5539.169124631338;5644.576656750678;4547.386102876202;4817.819634189505;5276.472352764693;5448.727563621882;5507.118860982384 15:31:31 INFO - PROCESS | 1654 | |5;yui.html;598.2560835165492;691.4053913634274;692.3526502719957;738.1584993107862;795.4829360915406;13749.725005499897;13772.173669579832;13821.723565528708;14065.929670351634;14402.711945761092;12968.027496847264;12975.286359250238;13101.868981310185;13102.27937463439;13131.868681313184;800.4276941986478;804.4489524675587;815.7838172884191;815.7919730468752;816.0819078536646;634.7048622390588;654.7871941618969;658.7760161545074;785.1951749456796;811.1037303779327;853.2619284319081;855.7176131876466;857.0743596915316;866.3285953386113;868.2966796894505;850.1456036683155;853.0914575976627;853.9188777066138;854.0989256334565;857.2499063319596;497.7496944949486;508.461031306816;619.8729260501586;638.2564312575844;640.5068097564896;14322.64193395163;14334.784978225334;14335.713285734331;14359.78460323096;14629.41482340705;13140.868591314156;13393.933030334785;13450.394732237073;13454.865451345458;13481.730365392637;11308.773824523545;11744.647660570197;11899;12259.141860069798;12282.447289871978;4365.2142614329405;4391.341298805173;4420.80106395213;4577.885552861172;4596.3794887690065;91616.99999999933;91716.08283917142;92576.53711731399;92727.53636231844;95582;8870.423422477648;10078.697639070839;10377;10565.471726413649;10575.71067608798;20478;20488.69266960997;20527.58944821095;20763.27328543524;20784;7201.89197162043;7249.927500725031;7446.032015837906;7677.692892284246;7742.458027937991;4091.1408604192857;4098.5286692030795;4163.812628431728;4164.637842457239;4179.185058913544;3321.9169520762175;3361.865525379006;3446.7070299024863;3692.5753538342897;3864.439656249828;687.7902239816904;696.5472442912046;713.5290708132608;722.2019668266595;724;610.4140025575408;622.9283632382244;631.6241836107508;642.7658894921761;661.5435349608755;8550;9108.81782364349;9120.222015912508;9132.840871370223;9182.86225706615;557.027986164138;566.1422944239481;590.4513871631525;623.5821999260506;641.4099028893429;4107.359101105725;4467.128909862546;4487.820487180542;4505.144022635689;4525.954740452619;73194.60832350075;79907;95664;119346.40326798485;121364;102553;104270.47864760627;104711.42932856013;106892;108836;79135.60432197803;84419;85968;88070.55964720264;91235;8629.827403452022;8635.136486351315;8644.35167362463;8691.95654021726;8942.955285223532;47238.055238895715;48398;48612.2708159385;49318.75340623417;50882.745586271834;47839.76080119577;52650.68373290698;54674.72662636662;61412.69293653503;65014.02478962914;12103.092268080105;12259.08941042455;12352.135350525736;12467.5012999479;12562.937185314015;11167.832482512931;13669.906407487577;14066.859331406555;14347.56957291283;14353.784693229818;11904.239838937989;13171.67070823237;13316.533921312708;13327.267000314689;13843.377048032662;12641.810372844599;12898.806517902427;13369.331533423485;13680.179189248676;13742.65643358924;12428.75142497127;12529.122961392615;12788.360581970679;12794.936025319814;12813.295268760308;9288.11762882525;9927.354721943051;10880.945595271973;11030.779384412428;11359.318440893572;10919.672409827717;11098.056665183227;11269.830952535554;11277.266977646426;11402.600908968147 15:31:31 INFO - PROCESS | 1654 | __end_tp_report 15:31:31 INFO - PROCESS | 1654 | __start_cc_report 15:31:31 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1746 15:31:31 INFO - PROCESS | 1654 | __end_cc_report 15:31:31 INFO - PROCESS | 1654 | __startTimestamp1484436691828__endTimestamp 15:31:31 INFO - PROCESS | 1654 | 15:31:31 INFO - PROCESS | 1654 | ------- Summary: start ------- 15:31:31 INFO - PROCESS | 1654 | Number of tests: 6 15:31:31 INFO - PROCESS | 1654 | 15:31:31 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5779.16 Median:6045.73 stddev:662.12 (11.0%) stddev-sans-first:635.64 15:31:31 INFO - PROCESS | 1654 | Values: 3328.8 3353.3 3355.6 3377.3 3381.9 5704.8 5724.9 5779.5 5800.8 5826.0 5739.7 5814.5 5836.3 5836.3 5839.8 5255.3 5256.8 5295.5 5324.5 5342.9 4260.4 5234.0 5237.2 5241.6 5364.9 5338.4 5348.3 5352.4 5356.9 5366.8 5345.2 5353.3 5354.3 5356.3 5413.2 5134.9 5155.5 5194.6 5285.1 5305.1 5655.9 5671.2 5694.5 5749.4 5757.2 6216.3 6229.7 6273.7 6279.0 6287.3 6254.9 6388.7 6409.9 6411.6 6423.6 5691.2 5726.5 5735.5 5814.8 5929.0 6297.2 6331.6 6351.0 6363.0 6370.1 6208.9 6222.7 6232.0 6236.2 6241.0 6117.3 6206.7 6276.2 6321.3 6330.7 6297.9 6311.9 6330.9 6331.8 6336.2 6265.6 6301.8 6344.2 6349.0 6356.9 4844.0 4884.4 4960.3 4970.4 4980.6 4442.8 4452.5 4481.2 4487.6 4499.1 5839.3 5849.0 5854.8 5866.4 5868.4 5270.6 6373.7 6375.1 6398.2 6407.0 5592.7 5681.1 5697.8 5755.3 5783.0 5389.1 5396.2 5420.6 5436.9 5440.8 5944.3 5971.1 6334.0 6342.1 6345.1 4663.5 6254.2 6310.7 6351.5 6371.2 6291.7 6305.7 6305.9 6309.1 6370.9 6239.5 6248.7 6273.5 6290.1 6297.4 6164.6 6211.9 6217.5 6227.3 6263.0 5156.0 5829.0 6091.8 6114.6 6141.1 6153.7 6211.7 6225.8 6226.7 6233.3 6061.1 6066.9 6076.0 6076.1 6084.2 6045.7 6122.2 6134.8 6168.4 6187.8 6002.4 6073.2 6106.5 6127.8 6136.0 15:31:31 INFO - PROCESS | 1654 | 15:31:31 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:67256.76 Median:37732.96 stddev:151389.72 (401.2%) stddev-sans-first:151731.33 15:31:31 INFO - PROCESS | 1654 | Values: 458.4 460.2 526.8 529.6 535.7 29195.0 37884.6 38888.0 38972.8 39028.6 39972.6 40254.6 40335.0 40350.4 40377.8 9099.5 9249.1 9255.8 9257.7 9263.4 9073.3 9153.6 9155.7 9165.5 9175.1 9141.3 9171.6 9177.6 9183.6 9194.8 7293.4 9110.9 9148.5 9162.3 9175.8 9122.5 9193.4 9198.5 9201.8 9206.6 31161.9 31240.7 33790.0 35595.3 37691.5 3109.2 3192.6 3245.3 3421.0 3563.9 28441.7 28462.6 28898.9 29244.4 29505.9 2990.5 3100.8 3112.8 3114.5 3116.8 507893.0 510656.0 511514.9 512004.0 525070.4 31681.8 58097.1 58608.5 58760.4 58872.0 32659.7 33518.5 33859.5 33864.0 33908.0 13960.2 14036.0 14052.9 14056.4 14063.9 11882.0 11944.4 11947.6 11949.1 11958.1 7562.9 7566.2 7648.7 7653.0 7673.1 2852.0 3166.4 3190.4 3207.7 3217.4 901.7 937.3 943.7 995.7 1008.3 22444.7 28246.9 28752.7 28940.4 29103.7 1024.8 1025.5 1043.5 1087.7 1177.7 14345.4 14391.9 14769.3 14966.4 15070.0 628494.0 633069.0 635427.0 635726.0 636064.0 45294.0 54426.5 54947.0 55026.7 55042.2 1098.0 1201.2 1253.0 1353.3 1375.5 25011.7 29049.9 30312.2 30822.7 31053.9 50530.7 51066.0 51256.2 51269.0 51439.7 535299.0 540355.0 540944.0 542399.0 544496.0 22187.8 22758.9 25246.2 25651.4 25695.0 44144.0 44383.8 44912.1 44956.9 46495.1 37127.6 37774.4 38256.2 39518.0 39546.6 36548.6 36642.5 37787.0 37921.6 37957.0 35978.8 36485.5 36673.8 36955.8 37057.3 31593.8 33131.3 33368.8 33394.0 33411.3 20936.6 24777.8 24799.4 24812.9 25191.7 15:31:31 INFO - PROCESS | 1654 | 15:31:31 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:32442.99 Median:373.16 stddev:91147.32 (24425.8%) stddev-sans-first:91371.16 15:31:31 INFO - PROCESS | 1654 | Values: 347.5 366.1 371.1 375.2 398.0 14508.6 14692.6 15765.8 19909.0 20379.9 16522.4 19625.5 20091.9 20288.9 20473.5 5765.8 6056.2 6062.9 6064.1 6068.8 5333.1 5794.9 5955.9 6029.8 6038.5 5699.0 5953.2 5954.4 5958.3 5958.3 5868.8 5990.8 5993.4 5994.3 5994.9 5049.2 5237.6 5243.6 5252.6 5336.4 12777.3 12933.8 13113.7 13138.5 13146.6 2131.4 2205.6 2250.4 2269.2 2299.6 9551.3 10090.8 10170.1 13046.9 13718.3 1800.5 1807.3 1829.9 1875.4 1903.5 92939.0 99481.0 104802.4 124679.0 130192.0 9430.7 10460.9 10502.4 11571.7 11703.2 14098.9 14219.0 14279.5 14436.9 14561.0 8328.1 8390.2 8460.6 8560.2 8567.7 8107.7 8285.0 8335.7 8414.0 8475.9 4417.2 4524.1 4551.4 4554.5 4568.2 1859.8 1880.9 1884.1 1888.0 1920.5 828.4 852.4 900.9 918.6 1026.9 17683.7 21087.6 21342.9 21635.2 21722.8 910.8 943.0 949.6 951.9 978.4 9469.3 10315.3 11087.7 11114.0 11135.3 520690.0 522009.0 524279.0 559920.0 596986.0 96942.0 113460.0 114183.0 114205.0 118272.0 934.1 944.1 987.7 988.8 989.2 18622.0 19226.4 19444.9 19522.0 19537.5 52804.2 53447.0 53455.7 54768.0 55244.7 79581.0 80041.0 80588.2 82370.0 82484.0 5477.1 6658.0 6859.5 7851.1 8289.0 11431.3 12151.0 12253.8 12583.4 12621.3 9898.2 10685.3 10789.4 10982.2 11137.7 10659.6 10665.7 10678.5 10917.3 11278.3 10911.5 11035.4 11402.1 11409.7 11497.9 7913.5 8135.0 8137.5 8150.5 8150.7 9696.7 10292.4 10401.8 10572.5 11220.3 15:31:31 INFO - PROCESS | 1654 | 15:31:31 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5379.95 Median:5166.22 stddev:2952.09 (57.1%) stddev-sans-first:2945.70 15:31:31 INFO - PROCESS | 1654 | Values: 1463.1 1489.4 1632.2 1763.7 1807.8 7940.3 8298.8 8322.3 8326.0 8336.6 7377.9 7610.5 7619.2 8008.9 8115.9 2278.6 2317.8 2320.3 2320.4 2326.8 2220.9 2253.4 2258.7 2259.9 2271.1 2291.7 2297.2 2299.9 2301.8 2327.2 2283.6 2288.6 2314.4 2314.9 2317.8 2308.0 2339.3 2341.2 2342.8 2345.7 8220.4 8253.3 8322.7 8339.7 8383.1 5014.8 5178.2 5189.4 5190.8 5210.9 2972.6 3220.3 3254.0 3524.3 3531.4 1042.9 1046.9 1071.4 1083.8 1086.5 10783.8 10843.9 10909.4 10913.5 10922.1 4400.3 4407.9 4489.3 4496.3 4685.7 9313.3 9333.1 9338.2 9342.3 9353.4 5098.4 5164.4 5165.7 5166.7 5176.2 4128.9 4211.9 4371.5 4680.6 4746.2 3725.8 3742.3 3813.1 3840.9 3862.4 1233.8 1238.2 1242.5 1252.2 1253.3 862.7 880.9 890.5 892.0 895.4 5505.6 5586.0 5610.4 5667.5 5890.6 868.1 877.6 882.7 908.9 910.6 3597.7 3646.3 3686.3 3805.7 3816.3 9443.0 9775.3 9967.2 10098.7 10105.3 10009.8 10016.8 10028.8 10029.5 10033.8 7667.9 7726.7 7734.3 7808.9 7828.8 5285.6 5359.4 5389.3 5529.9 5620.6 9199.4 9363.5 9375.0 9401.4 9411.2 9041.1 9461.1 9584.3 9587.1 9607.2 6242.9 6469.4 6490.9 6648.7 6775.9 7585.8 7769.8 7890.2 7945.6 7967.1 6970.4 7049.6 7082.8 7103.6 7192.3 6760.1 6927.2 6966.2 7047.2 7049.3 5516.5 6380.0 6612.9 6622.6 6667.7 4731.6 4775.5 4807.0 4840.0 4862.8 4705.0 4713.8 4727.3 4908.2 4971.8 15:31:31 INFO - PROCESS | 1654 | 15:31:31 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4413.55 Median:4996.53 stddev:1378.85 (27.6%) stddev-sans-first:1347.58 15:31:31 INFO - PROCESS | 1654 | Values: 292.4 296.8 305.1 307.8 310.7 5338.7 5368.6 5369.2 5378.2 5421.6 5003.6 5138.0 5162.7 5163.7 5267.2 3445.9 3497.4 3747.9 4035.8 4047.0 3634.7 3981.0 4055.7 4061.3 4064.7 3720.3 3978.7 3993.7 3999.3 4019.9 3298.9 3926.1 3951.3 3965.9 3993.7 2996.6 3425.4 3447.4 3452.8 3458.3 4588.0 4989.5 5146.8 5159.3 5192.4 3895.6 4532.9 4534.6 4542.4 4553.2 4947.5 5154.0 5501.5 5523.4 5534.6 3772.5 3775.2 3785.5 3787.5 3811.6 5239.0 5473.6 5795.0 5807.2 5808.9 3352.3 3794.6 3804.8 3840.0 3859.5 3717.2 3893.4 3898.6 3905.2 3925.4 3923.2 4407.7 4414.4 4531.2 4576.4 4488.0 5051.4 5583.9 5586.9 5595.8 1107.2 1128.4 1193.6 1209.4 1221.7 702.9 727.6 799.8 803.9 817.6 4045.4 4640.5 5251.3 5257.4 5271.0 4775.2 5052.5 5617.3 5717.5 5743.8 5020.0 5190.7 5228.7 5234.3 5248.0 2694.9 2741.4 2832.8 2845.7 2856.6 4945.7 5070.9 5114.7 5246.4 5381.2 4898.9 5357.2 5528.8 5569.4 5637.7 4700.6 5006.6 5118.2 5531.6 5659.9 4640.3 5038.5 5342.4 5408.1 5574.6 4959.7 5739.9 5741.7 5745.0 5751.4 4878.5 5944.5 5986.8 6054.0 6064.6 2966.7 3227.0 3281.7 3854.4 3879.6 4761.5 5737.0 5764.0 5764.5 5773.8 4647.7 4651.3 5650.1 5683.9 5726.7 4625.5 5399.5 5547.2 5590.7 5600.4 4697.6 4917.6 5215.7 5235.2 5481.3 5023.6 5488.6 5524.3 5539.2 5644.6 4547.4 4817.8 5276.5 5448.7 5507.1 15:31:31 INFO - PROCESS | 1654 | 15:31:31 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:19983.04 Median:4515.55 stddev:29477.67 (652.8%) stddev-sans-first:29524.42 15:31:31 INFO - PROCESS | 1654 | Values: 598.3 691.4 692.4 738.2 795.5 13749.7 13772.2 13821.7 14065.9 14402.7 12968.0 12975.3 13101.9 13102.3 13131.9 800.4 804.4 815.8 815.8 816.1 634.7 654.8 658.8 785.2 811.1 853.3 855.7 857.1 866.3 868.3 850.1 853.1 853.9 854.1 857.2 497.7 508.5 619.9 638.3 640.5 14322.6 14334.8 14335.7 14359.8 14629.4 13140.9 13393.9 13450.4 13454.9 13481.7 11308.8 11744.6 11899.0 12259.1 12282.4 4365.2 4391.3 4420.8 4577.9 4596.4 91617.0 91716.1 92576.5 92727.5 95582.0 8870.4 10078.7 10377.0 10565.5 10575.7 20478.0 20488.7 20527.6 20763.3 20784.0 7201.9 7249.9 7446.0 7677.7 7742.5 4091.1 4098.5 4163.8 4164.6 4179.2 3321.9 3361.9 3446.7 3692.6 3864.4 687.8 696.5 713.5 722.2 724.0 610.4 622.9 631.6 642.8 661.5 8550.0 9108.8 9120.2 9132.8 9182.9 557.0 566.1 590.5 623.6 641.4 4107.4 4467.1 4487.8 4505.1 4526.0 73194.6 79907.0 95664.0 119346.4 121364.0 102553.0 104270.5 104711.4 106892.0 108836.0 79135.6 84419.0 85968.0 88070.6 91235.0 8629.8 8635.1 8644.4 8692.0 8943.0 47238.1 48398.0 48612.3 49318.8 50882.7 47839.8 52650.7 54674.7 61412.7 65014.0 12103.1 12259.1 12352.1 12467.5 12562.9 11167.8 13669.9 14066.9 14347.6 14353.8 11904.2 13171.7 13316.5 13327.3 13843.4 12641.8 12898.8 13369.3 13680.2 13742.7 12428.8 12529.1 12788.4 12794.9 12813.3 9288.1 9927.4 10880.9 11030.8 11359.3 10919.7 11098.1 11269.8 11277.3 11402.6 15:31:31 INFO - PROCESS | 1654 | -------- Summary: end -------- 15:31:31 INFO - PROCESS | 1654 | 15:31:32 INFO - TEST-INFO | 1654: exit 0 15:31:32 INFO - TEST-OK | dromaeo_css | took 1118872ms 15:31:32 INFO - TEST-START | kraken 15:31:32 INFO - operating with platform_type : mac_ 15:31:32 INFO - Initialising browser for kraken test... 15:31:32 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmphWyDFX/profile http://localhost:49240/getInfo.html) 15:31:34 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 15:31:34 INFO - PROCESS | 1660 | colorDepth:24 15:31:34 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 15:31:34 INFO - PROCESS | 1660 | __metrics 15:31:35 INFO - PROCESS | 1660 | 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" 15:31:35 INFO - PROCESS | 1660 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:31:35 INFO - TEST-INFO | 1660: exit 0 15:31:35 INFO - Browser initialized. 15:31:35 INFO - Running cycle 1/1 for kraken test... 15:31:35 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmphWyDFX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:31:48 INFO - PROCESS | 1661 | RSS: Main: 222117888 15:31:48 INFO - PROCESS | 1661 | 15:31:51 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 15:31:52 INFO - PROCESS | 1661 | RSS: Main: 272265216 15:31:52 INFO - PROCESS | 1661 | 15:31:54 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 15:31:55 INFO - PROCESS | 1661 | RSS: Main: 283279360 15:31:55 INFO - PROCESS | 1661 | 15:31:58 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 15:31:59 INFO - PROCESS | 1661 | RSS: Main: 380628992 15:31:59 INFO - PROCESS | 1661 | 15:32:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 15:32:01 INFO - PROCESS | 1661 | RSS: Main: 283684864 15:32:01 INFO - PROCESS | 1661 | 15:32:03 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:32:04 INFO - PROCESS | 1661 | RSS: Main: 284569600 15:32:04 INFO - PROCESS | 1661 | 15:32:07 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 15:32:07 INFO - PROCESS | 1661 | RSS: Main: 324870144 15:32:07 INFO - PROCESS | 1661 | 15:32:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 15:32:11 INFO - PROCESS | 1661 | RSS: Main: 325984256 15:32:11 INFO - PROCESS | 1661 | 15:32:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 15:32:14 INFO - PROCESS | 1661 | RSS: Main: 325345280 15:32:14 INFO - PROCESS | 1661 | 15:32:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:32:16 INFO - PROCESS | 1661 | RSS: Main: 298999808 15:32:16 INFO - PROCESS | 1661 | 15:32:18 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:32:19 INFO - PROCESS | 1661 | RSS: Main: 302100480 15:32:19 INFO - PROCESS | 1661 | 15:32:21 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:32:22 INFO - PROCESS | 1661 | RSS: Main: 304578560 15:32:22 INFO - PROCESS | 1661 | 15:32:25 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:32:25 INFO - PROCESS | 1661 | RSS: Main: 316293120 15:32:25 INFO - PROCESS | 1661 | 15:32:28 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:32:29 INFO - PROCESS | 1661 | RSS: Main: 315080704 15:32:29 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 15:32:31 INFO - PROCESS | 1661 | RSS: Main: 359190528 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | __start_tp_report 15:32:31 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 15:32:31 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 15:32:31 INFO - PROCESS | 1661 | |i|pagename|runs| 15:32:31 INFO - PROCESS | 1661 | |0;ai-astar;116;114;114;119;166;161;112;114;113;114 15:32:31 INFO - PROCESS | 1661 | |1;audio-beat-detection;147;109;143;176;141;112;121;138;147;109 15:32:31 INFO - PROCESS | 1661 | |2;audio-dft;180;183;186;184;186;185;182;182;185;190 15:32:31 INFO - PROCESS | 1661 | |3;audio-fft;82;59;58;88;59;59;58;59;81;58 15:32:31 INFO - PROCESS | 1661 | |4;audio-oscillator;86;70;94;70;67;68;88;67;68;90 15:32:31 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;93;132;115;86;88;85;89;87;89;85 15:32:31 INFO - PROCESS | 1661 | |6;imaging-darkroom;94;95;94;94;95;94;96;95;94;94 15:32:31 INFO - PROCESS | 1661 | |7;imaging-desaturate;84;82;81;79;80;81;84;84;84;84 15:32:31 INFO - PROCESS | 1661 | |8;json-parse-financial;54;55;54;53;56;58;57;56;56;57 15:32:31 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;64;70;71;80;73;71;70;72;71;73 15:32:31 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;85;81;98;103;102;77;83;83;82;84 15:32:31 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;141;139;145;144;138;150;160;159;250;150 15:32:31 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;166;227;201;164;163;163;167;172;164;143 15:32:31 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;67;68;67;67;67;70;80;86;85;85 15:32:31 INFO - PROCESS | 1661 | __end_tp_report 15:32:31 INFO - PROCESS | 1661 | __start_cc_report 15:32:31 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1998 15:32:31 INFO - PROCESS | 1661 | __end_cc_report 15:32:31 INFO - PROCESS | 1661 | __startTimestamp1484436751553__endTimestamp 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | ------- Summary: start ------- 15:32:31 INFO - PROCESS | 1661 | Number of tests: 14 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:124.30 Median:115.00 stddev:20.78 (18.1%) stddev-sans-first:21.82 15:32:31 INFO - PROCESS | 1661 | Values: 116.0 114.0 114.0 119.0 166.0 161.0 112.0 114.0 113.0 114.0 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:134.30 Median:142.00 stddev:21.47 (15.1%) stddev-sans-first:22.28 15:32:31 INFO - PROCESS | 1661 | Values: 147.0 109.0 143.0 176.0 141.0 112.0 121.0 138.0 147.0 109.0 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:184.30 Median:185.00 stddev:2.79 (1.5%) stddev-sans-first:2.49 15:32:31 INFO - PROCESS | 1661 | Values: 180.0 183.0 186.0 184.0 186.0 185.0 182.0 182.0 185.0 190.0 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:66.10 Median:59.00 stddev:12.26 (20.8%) stddev-sans-first:11.58 15:32:31 INFO - PROCESS | 1661 | Values: 82.0 59.0 58.0 88.0 59.0 59.0 58.0 59.0 81.0 58.0 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:78.00 stddev:11.15 (14.3%) stddev-sans-first:11.32 15:32:31 INFO - PROCESS | 1661 | Values: 86.0 70.0 94.0 70.0 67.0 68.0 88.0 67.0 68.0 90.0 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:94.90 Median:87.50 stddev:15.77 (18.0%) stddev-sans-first:16.71 15:32:31 INFO - PROCESS | 1661 | Values: 93.0 132.0 115.0 86.0 88.0 85.0 89.0 87.0 89.0 85.0 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 15:32:31 INFO - PROCESS | 1661 | Values: 94.0 95.0 94.0 94.0 95.0 94.0 96.0 95.0 94.0 94.0 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:82.30 Median:84.00 stddev:1.95 (2.3%) stddev-sans-first:1.96 15:32:31 INFO - PROCESS | 1661 | Values: 84.0 82.0 81.0 79.0 80.0 81.0 84.0 84.0 84.0 84.0 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:1.58 (2.8%) stddev-sans-first:1.56 15:32:31 INFO - PROCESS | 1661 | Values: 54.0 55.0 54.0 53.0 56.0 58.0 57.0 56.0 56.0 57.0 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.50 stddev:3.92 (5.5%) stddev-sans-first:3.08 15:32:31 INFO - PROCESS | 1661 | Values: 64.0 70.0 71.0 80.0 73.0 71.0 70.0 72.0 71.0 73.0 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:83.00 stddev:9.44 (11.4%) stddev-sans-first:9.96 15:32:31 INFO - PROCESS | 1661 | Values: 85.0 81.0 98.0 103.0 102.0 77.0 83.0 83.0 82.0 84.0 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:157.60 Median:150.00 stddev:33.35 (22.2%) stddev-sans-first:34.83 15:32:31 INFO - PROCESS | 1661 | Values: 141.0 139.0 145.0 144.0 138.0 150.0 160.0 159.0 250.0 150.0 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:166.50 stddev:23.68 (14.2%) stddev-sans-first:24.98 15:32:31 INFO - PROCESS | 1661 | Values: 166.0 227.0 201.0 164.0 163.0 163.0 167.0 172.0 164.0 143.0 15:32:31 INFO - PROCESS | 1661 | 15:32:31 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.20 Median:75.00 stddev:8.63 (11.5%) stddev-sans-first:8.75 15:32:31 INFO - PROCESS | 1661 | Values: 67.0 68.0 67.0 67.0 67.0 70.0 80.0 86.0 85.0 85.0 15:32:31 INFO - PROCESS | 1661 | -------- Summary: end -------- 15:32:31 INFO - PROCESS | 1661 | 15:32:32 INFO - TEST-INFO | 1661: exit 0 15:32:32 INFO - TEST-OK | kraken | took 60143ms 15:32:32 INFO - SUITE-END | took 1179s 15:32:32 INFO - Completed test suite (00:19:39) 15:32:32 INFO - javascript benchmark 15:32:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3328.818269514321, 3353.2958078803445, 3355.6141043779967, 3377.273886114485, 3381.932361352773, 5704.800331988381, 5724.856878578033, 5779.537636989041, 5800.825975220745, 5826.0, 5739.713014349287, 5814.534837213024, 5836.2996440427105, 5836.32882218545, 5839.824805255849, 5255.316808814849, 5256.842294731164, 5295.523402893738, 5324.494173053554, 5342.866428339284, 4260.42484264625, 5234.0, 5237.162054071349, 5241.633085684004, 5364.865878353034, 5338.386085600147, 5348.277982472356, 5352.411234764173, 5356.91964620531, 5366.758495867695, 5345.1982202669715, 5353.330833645794, 5354.303940487751, 5356.330458692663, 5413.188021796723, 5134.922976155361, 5155.510226528474, 5194.584433245369, 5285.101532739405, 5305.124654432014, 5655.8586035349035, 5671.177679236487, 5694.487496125327, 5749.367569567345, 5757.222774925373, 6216.254049514042, 6229.719662615148, 6273.749050037993, 6278.968605156945, 6287.308396076428, 6254.906176407358, 6388.680565971729, 6409.871802563923, 6411.647359395231, 6423.582467139621, 5691.231683722727, 5726.455986681259, 5735.541156707453, 5814.79648212319, 5929.021711417654, 6297.1813664223355, 6331.588446750947, 6351.0, 6363.0, 6370.0763389308295, 6208.875822483525, 6222.688865556704, 6232.034034724625, 6236.158118654014, 6241.0, 6117.2582339092005, 6206.689665516707, 6276.152719382825, 6321.273053598803, 6330.715117819617, 6297.937020629826, 6311.873762524724, 6330.936690633035, 6331.810045698637, 6336.239651241881, 6265.561410701207, 6301.842453938597, 6344.238691357066, 6349.0, 6356.904646430307, 4843.975780121147, 4884.389451318585, 4960.330355401975, 4970.403551573762, 4980.626453015966, 4442.800073996613, 4452.532484089189, 4481.1933851906315, 4487.551244875552, 4499.055198408306, 5839.299284085937, 5848.9707551462825, 5854.795082172103, 5866.384029676908, 5868.38381969887, 5270.552003079782, 6373.745050198033, 6375.075614036032, 6398.200224971879, 6407.0, 5592.692401917933, 5681.0910254359105, 5697.772089116472, 5755.251817263813, 5783.0, 5389.110796718576, 5396.244525766396, 5420.647657902303, 5436.864078397993, 5440.809571664972, 5944.286685597756, 5971.104334349882, 6334.018227174795, 6342.143810585512, 6345.079963405373, 4663.533646635309, 6254.21822272216, 6310.652914089769, 6351.460125889351, 6371.23545174582, 6291.653959032297, 6305.747770089145, 6305.90541141874, 6309.148264984352, 6370.904436433365, 6239.500839932885, 6248.718807653758, 6273.498120150286, 6290.0879372491645, 6297.433231009231, 6164.568480206342, 6211.937880621136, 6217.4715149212825, 6227.252729672468, 6263.0, 5155.9742201288755, 5828.970855145867, 6091.7563297467605, 6114.633122012693, 6141.087130295657, 6153.692315384303, 6211.720472578835, 6225.844353891189, 6226.657533835682, 6233.345498722751, 6061.121137435135, 6066.908996365147, 6075.969620151871, 6076.149339092619, 6084.209052823282, 6045.727942242522, 6122.173506576733, 6134.773364742421, 6168.35232300602, 6187.752489900354, 6002.429769172097, 6073.210482637405, 6106.542009349228, 6127.754889804358, 6136.0], "value": 5737.170488407127, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.44757067733383, 460.22452168096737, 526.807715183958, 529.6477842234914, 535.7356638333534, 29195.0, 37884.62115378845, 38888.027799305, 38972.80513597428, 39028.60971390285, 39972.600273997254, 40254.597454025454, 40335.0, 40350.39474407879, 40377.798111009404, 9099.454032758023, 9249.075092490764, 9255.814883702322, 9257.722268331961, 9263.351565390425, 9073.274138068939, 9153.63385464584, 9155.72532824013, 9165.495897725623, 9175.128362805523, 9141.268698504136, 9171.63313467464, 9177.587008584629, 9183.632654693803, 9194.77013074675, 7293.416526677853, 9110.908890911072, 9148.4968326742, 9162.267018638493, 9175.77060573491, 9122.498262595554, 9193.448393096436, 9198.540072996324, 9201.769955751093, 9206.63173473067, 31161.90933317323, 31240.687593124232, 33789.98630041103, 35595.28809423797, 37691.49234030635, 3109.2226943264186, 3192.6009248843893, 3245.302260014085, 3421.007907706762, 3563.893083207508, 28441.71558284432, 28462.573061404095, 28898.855505722546, 29244.41511169786, 29505.852470737722, 2990.4617168909585, 3100.8449577521033, 3112.766542509321, 3114.5328200770064, 3116.812991220534, 507893.0, 510656.0, 511514.88485115045, 512004.0, 525070.3746481282, 31681.84159079236, 58097.128543071885, 58608.534786629825, 58760.41239587549, 58872.0, 32659.673403266137, 33518.49722254168, 33859.492107618404, 33864.0, 33908.0, 13960.162390256617, 14036.0, 14052.929735351463, 14056.367463464168, 14063.85936140646, 11882.0, 11944.402779861146, 11947.581834635746, 11949.10381721357, 11958.103142264365, 7562.924370756222, 7566.205548417337, 7648.694052237959, 7653.0, 7673.079230492377, 2852.0160544612077, 3166.4142133705336, 3190.4257233697695, 3207.7273431758094, 3217.420864244412, 901.733988473402, 937.3110763588757, 943.6602822983721, 995.716220877061, 1008.3161120927921, 22444.66333005006, 28246.85876570604, 28752.712472875002, 28940.42119157647, 29103.708962910525, 1024.7694268789462, 1025.5026312238558, 1043.4574021508772, 1087.7335052911928, 1177.7232350397649, 14345.354459049368, 14391.928040359731, 14769.261536923326, 14966.401343946338, 15070.0204486708, 628494.0, 633069.0, 635427.0, 635726.0, 636064.0, 45294.0, 54426.45573544293, 54947.0, 55026.724866375414, 55042.17436738452, 1097.9835302470628, 1201.2072032458536, 1252.993735031319, 1353.3301015997145, 1375.532319011541, 25011.749882500942, 29049.85475072611, 30312.242193945327, 30822.69177308198, 31053.864870671496, 50530.74734626451, 51066.0, 51256.23115653193, 51269.0, 51439.742801285756, 535299.0, 540355.0, 540944.0, 542399.0, 544496.0, 22187.77812221857, 22758.86205689742, 25246.242612720915, 25651.358716031504, 25694.972201111745, 44144.0, 44383.77808110939, 44912.10175796531, 44956.87607809831, 46495.07009859716, 37127.62872371242, 37774.43338349872, 38256.23487530178, 39518.0, 39546.60453395429, 36548.63451365559, 36642.45036324404, 37787.0, 37921.62078379181, 37957.0, 35978.8201058993, 36485.45271820872, 36673.81663091668, 36955.81522092372, 37057.25885482329, 31593.8420307897, 33131.337373252885, 33368.832090876705, 33393.99818005464, 33411.33177336488, 20936.58126837485, 24777.752222477546, 24799.380015499755, 24812.875935620206, 25191.74808251894], "value": 17831.952730767556, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.46316940327193, 366.10669965284706, 371.10563541864116, 375.21579898013147, 397.9522457305124, 14508.564743057712, 14692.559223223307, 15765.763513547307, 19909.00454977249, 20379.898100509512, 16522.42171523997, 19625.509362265915, 20091.899540502312, 20288.89855550724, 20473.488162795937, 5765.7981970631035, 6056.21269235001, 6062.939370606304, 6064.090386442025, 6068.8179354619215, 5333.146696528556, 5794.855128621776, 5955.868385006835, 6029.788957386492, 6038.51691864652, 5698.971505142468, 5953.166556682068, 5954.434328738785, 5958.255218097738, 5958.344582095966, 5868.823935281927, 5990.820275391745, 5993.370696076893, 5994.340622531518, 5994.850128746794, 5049.166887463564, 5237.633365664405, 5243.580513558906, 5252.6323157379, 5336.38631557372, 12777.29724865132, 12933.805992910115, 13113.737725245537, 13138.474461021548, 13146.60560183196, 2131.360591822447, 2205.5809396214827, 2250.437390652337, 2269.239804665439, 2299.620562607168, 9551.331406801526, 10090.848637270374, 10170.135538479239, 13046.869531304661, 13718.314084295745, 1800.522861441719, 1807.2951548895942, 1829.9176537055864, 1875.3623767918837, 1903.5050886769402, 92939.0, 99481.0051899479, 104802.42796358137, 124679.0, 130192.0, 9430.669926552675, 10460.895391045991, 10502.369857808557, 11571.6528504145, 11703.23928944616, 14098.929505352613, 14219.004669673024, 14279.500217492545, 14436.855631443761, 14560.980731348704, 8328.08391076981, 8390.244877960891, 8460.619272132768, 8560.229579337889, 8567.74296771098, 8107.675692972215, 8285.0, 8335.705972557224, 8413.957930210432, 8475.872861907077, 4417.204903117406, 4524.140413321458, 4551.408316918845, 4554.521775213555, 4568.223402021597, 1859.7675290588677, 1880.9059547022594, 1884.1427150646423, 1887.981120188808, 1920.5116035072651, 828.3911325175991, 852.394799692213, 900.9099090090939, 918.6187732091241, 1026.8870424253325, 17683.73474397885, 21087.578248434944, 21342.93285335727, 21635.242766503095, 21722.78277217239, 910.8497097978892, 942.9575669094908, 949.5964215208647, 951.8952915179325, 978.395431492353, 9469.289803264783, 10315.27793054494, 11087.72280692989, 11114.0, 11135.33188008722, 520690.0, 522009.0, 524279.0, 559920.0, 596986.0, 96942.0, 113460.0, 114183.0, 114205.0, 118272.0, 934.0845970948509, 944.1408318430058, 987.7234374375187, 988.7577543501889, 989.2333441582687, 18621.975791331064, 19226.423207303244, 19444.902775486033, 19522.023898805288, 19537.51156221049, 52804.20793688021, 53447.0, 53455.73272133614, 54768.0, 55244.72377638086, 79581.0, 80041.0, 80588.19411806042, 82370.0, 82484.0, 5477.096279113902, 6658.0, 6859.519833611599, 7851.05787305527, 8289.0, 11431.256968297366, 12151.027917766382, 12253.816192757295, 12583.370831458207, 12621.305828179538, 9898.15865651399, 10685.252032357661, 10789.40658263803, 10982.231243813176, 11137.665870023913, 10659.573617055232, 10665.733356666145, 10678.519466623866, 10917.344959302467, 11278.266912650652, 10911.544902396898, 11035.448227588427, 11402.144839137263, 11409.657710268706, 11497.885021149681, 7913.485623434458, 8134.959325203336, 8137.471064380797, 8150.470219435955, 8150.673973041012, 9696.660616878375, 10292.382457052601, 10401.84397234027, 10572.524236409226, 11220.326780393203], "value": 9165.98344594506, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1463.0855715178013, 1489.3967942983093, 1632.1757512456209, 1763.735439684048, 1807.764990551229, 7940.325072368848, 8298.792530186749, 8322.250997410232, 8326.0, 8336.624851881672, 7377.926220737791, 7610.54336739796, 7619.238076192378, 8008.879866801988, 8115.91884081159, 2278.5670722562745, 2317.8377513574055, 2320.3155069254612, 2320.385097949045, 2326.8254880883915, 2220.9222677206303, 2253.3803204118917, 2258.7402448718444, 2259.9435014124615, 2271.1256166375897, 2291.702078729763, 2297.2419101696482, 2299.8505097168713, 2301.827362947777, 2327.2320134355623, 2283.6003699352714, 2288.645259984705, 2314.3982564533258, 2314.8611083335054, 2317.7566355532595, 2307.9538409231723, 2339.251439539348, 2341.1571834139695, 2342.8125749939963, 2345.7185137783404, 8220.38347123968, 8253.33973282142, 8322.66709331626, 8339.708110216114, 8383.077861435237, 5014.824481143142, 5178.223266510016, 5189.42916279209, 5190.766415511273, 5210.895782084337, 2972.5689774982725, 3220.339830334776, 3253.9511907321407, 3524.259905419865, 3531.4349704047486, 1042.921780866438, 1046.9214808889287, 1071.3946620159586, 1083.8320060390652, 1086.538221255971, 10783.83824242637, 10843.945780271048, 10909.399983000932, 10913.454327283684, 10922.071623911977, 4400.273954797487, 4407.933880991788, 4489.304157855538, 4496.348029535699, 4685.742284174403, 9313.301502387349, 9333.113354231336, 9338.20625246848, 9342.346035777566, 9353.438793672402, 5098.362704661918, 5164.354455693038, 5165.74171291434, 5166.689998600096, 5176.249443830623, 4128.896777580585, 4211.852585159505, 4371.497277813029, 4680.602148817321, 4746.169420351424, 3725.795081270502, 3742.288965096677, 3813.122981714221, 3840.8655697050467, 3862.4399462078404, 1233.802591585342, 1238.2261086820738, 1242.5092088624942, 1252.1923359433115, 1253.254313683357, 862.6851199312331, 880.933929955256, 890.5146695051083, 891.9965039330752, 895.3508706187962, 5505.587080968943, 5585.96659617972, 5610.438956104357, 5667.546596272288, 5890.6171098879295, 868.0971789339143, 877.6489404238229, 882.717530390269, 908.895477020138, 910.63404892661, 3597.748157628941, 3646.3254297954963, 3686.262747450467, 3805.733598648068, 3816.31306364857, 9443.0, 9775.266854985905, 9967.202623790079, 10098.747531311628, 10105.292629515863, 10009.849852252222, 10016.849747253797, 10028.849567256497, 10029.498525073863, 10033.849492257623, 7667.923320766721, 7726.729564465217, 7734.303912647889, 7808.921910780933, 7828.804279893048, 5285.630005899704, 5359.356877174764, 5389.349241533873, 5529.889402212014, 5620.578456615852, 9199.402038867453, 9363.485008324335, 9374.968753437253, 9401.388909720847, 9411.200047995997, 9041.141091596288, 9461.101195386427, 9584.329096963145, 9587.089226523469, 9607.231421486404, 6242.906356404748, 6469.353064693493, 6490.902636460362, 6648.667566621746, 6775.864482710417, 7585.848283034198, 7769.844603108019, 7890.171531989058, 7945.562994035384, 7967.123616402307, 6970.442364610718, 7049.647517624201, 7082.787516374517, 7103.573785572882, 7192.280771922766, 6760.053592496956, 6927.238003819475, 6966.1988871280455, 7047.189573199148, 7049.29507049291, 5516.503514683698, 6379.972339140065, 6612.933870661232, 6622.63575503352, 6667.733290668319, 4731.597814185833, 4775.522447755336, 4807.0, 4840.0, 4862.781174847069, 4704.976475117603, 4713.7643117844655, 4727.267273572602, 4908.190148625579, 4971.825986090469], "value": 4368.208351244676, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.39474288223374, 296.8026262535414, 305.08322490914804, 307.80762023735167, 310.67999960041135, 5338.652987555806, 5368.62419630626, 5369.194620806871, 5378.24704541364, 5421.56328572928, 5003.574696150831, 5138.04754193406, 5162.716050617215, 5163.7418129093585, 5267.23625074364, 3445.8621655133766, 3497.3529896969144, 3747.9318394257643, 4035.7982100894983, 4047.0084829216885, 3634.745567810254, 3980.9848488635503, 4055.722447429065, 4061.3095773718537, 4064.7154699170924, 3720.274546463441, 3978.6817054635703, 3993.740406873544, 3999.3201155803436, 4019.939700904474, 3298.917527061819, 3926.055458406195, 3951.308521008812, 3965.9490235087724, 3993.700472464562, 2996.550517422404, 3425.400554902882, 3447.4311738563106, 3452.7583069185166, 3458.2564748578925, 4588.0, 4989.476105008958, 5146.819861304836, 5159.251908473288, 5192.376914770214, 3895.5714871364125, 4532.9320060199125, 4534.6372290216705, 4542.432195975503, 4553.248713962193, 4947.505249475023, 5153.994970980661, 5501.504864562208, 5523.4200408956885, 5534.557235421197, 3772.4529943158086, 3775.1505911170043, 3785.5268091488565, 3787.5265591801026, 3811.5606012151966, 5238.973805130988, 5473.589480788957, 5795.0, 5807.245058142415, 5808.88382232353, 3352.26250224955, 3794.582595914447, 3804.784956711574, 3839.9616003839606, 3859.4982652255026, 3717.1636381814415, 3893.4354518594646, 3898.5516665583255, 3905.2384784967226, 3925.4111883217174, 3923.2349691809823, 4407.735535867858, 4414.426124603781, 4531.18438681034, 4576.359309696645, 4487.967767413513, 5051.444341122474, 5583.8883222336135, 5586.91619625706, 5595.832125036255, 1107.1751545098791, 1128.3963079752293, 1193.6478738772082, 1209.3650833312406, 1221.712897469094, 702.8945658151216, 727.5998200989541, 799.7840583042662, 803.9155888631609, 817.5789468423767, 4045.4336392905016, 4640.512746161605, 5251.291075704808, 5257.373511310023, 5270.973645131749, 4775.2359622460235, 5052.545270925634, 5617.269754931833, 5717.485426311652, 5743.812184237705, 5019.974900125476, 5190.677966101698, 5228.660137091077, 5234.2672025915645, 5248.002879452892, 2694.85468675812, 2741.4105967216747, 2832.844193569332, 2845.7438830505353, 2856.557233628791, 4945.703257804544, 5070.898582028339, 5114.693118412907, 5246.396664383568, 5381.174883186359, 4898.851940814256, 5357.223202635674, 5528.834134975958, 5569.443055694398, 5637.7181140942785, 4700.576948074689, 5006.574441172541, 5118.232265160255, 5531.585131115183, 5659.858503537444, 4640.303954406866, 5038.505939559941, 5342.439043900335, 5408.053590621704, 5574.554035677217, 4959.710475276526, 5739.8565035874435, 5741.741621626953, 5745.023346031101, 5751.396103409249, 4878.512148785093, 5944.494717949023, 5986.790462333797, 6054.0, 6064.575479716377, 2966.6736658967066, 3227.0, 3281.7374610031616, 3854.4411060396646, 3879.5732469427776, 4761.500042495627, 5736.971315143398, 5764.0, 5764.452377024176, 5773.769049237983, 4647.7443740593235, 4651.325557794168, 5650.1072140833285, 5683.8863222736145, 5726.709316273524, 4625.537446255347, 5399.514043736082, 5547.223388725501, 5590.720463976866, 5600.439956004367, 4697.624190064718, 4917.6311776616185, 5215.6609820361555, 5235.246192525547, 5481.260029896072, 5023.648344615842, 5488.588355873246, 5524.254225679482, 5539.169124631338, 5644.576656750678, 4547.386102876202, 4817.819634189505, 5276.472352764693, 5448.727563621882, 5507.118860982384], "value": 3963.5812831463886, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.2560835165492, 691.4053913634274, 692.3526502719957, 738.1584993107862, 795.4829360915406, 13749.725005499897, 13772.173669579832, 13821.723565528708, 14065.929670351634, 14402.711945761092, 12968.027496847264, 12975.286359250238, 13101.868981310185, 13102.27937463439, 13131.868681313184, 800.4276941986478, 804.4489524675587, 815.7838172884191, 815.7919730468752, 816.0819078536646, 634.7048622390588, 654.7871941618969, 658.7760161545074, 785.1951749456796, 811.1037303779327, 853.2619284319081, 855.7176131876466, 857.0743596915316, 866.3285953386113, 868.2966796894505, 850.1456036683155, 853.0914575976627, 853.9188777066138, 854.0989256334565, 857.2499063319596, 497.7496944949486, 508.461031306816, 619.8729260501586, 638.2564312575844, 640.5068097564896, 14322.64193395163, 14334.784978225334, 14335.713285734331, 14359.78460323096, 14629.41482340705, 13140.868591314156, 13393.933030334785, 13450.394732237073, 13454.865451345458, 13481.730365392637, 11308.773824523545, 11744.647660570197, 11899.0, 12259.141860069798, 12282.447289871978, 4365.2142614329405, 4391.341298805173, 4420.80106395213, 4577.885552861172, 4596.3794887690065, 91616.99999999933, 91716.08283917142, 92576.53711731399, 92727.53636231844, 95582.0, 8870.423422477648, 10078.697639070839, 10377.0, 10565.471726413649, 10575.71067608798, 20478.0, 20488.69266960997, 20527.58944821095, 20763.27328543524, 20784.0, 7201.89197162043, 7249.927500725031, 7446.032015837906, 7677.692892284246, 7742.458027937991, 4091.1408604192857, 4098.5286692030795, 4163.812628431728, 4164.637842457239, 4179.185058913544, 3321.9169520762175, 3361.865525379006, 3446.7070299024863, 3692.5753538342897, 3864.439656249828, 687.7902239816904, 696.5472442912046, 713.5290708132608, 722.2019668266595, 724.0, 610.4140025575408, 622.9283632382244, 631.6241836107508, 642.7658894921761, 661.5435349608755, 8550.0, 9108.81782364349, 9120.222015912508, 9132.840871370223, 9182.86225706615, 557.027986164138, 566.1422944239481, 590.4513871631525, 623.5821999260506, 641.4099028893429, 4107.359101105725, 4467.128909862546, 4487.820487180542, 4505.144022635689, 4525.954740452619, 73194.60832350075, 79907.0, 95664.0, 119346.40326798485, 121364.0, 102553.0, 104270.47864760627, 104711.42932856013, 106892.0, 108836.0, 79135.60432197803, 84419.0, 85968.0, 88070.55964720264, 91235.0, 8629.827403452022, 8635.136486351315, 8644.35167362463, 8691.95654021726, 8942.955285223532, 47238.055238895715, 48398.0, 48612.2708159385, 49318.75340623417, 50882.745586271834, 47839.76080119577, 52650.68373290698, 54674.72662636662, 61412.69293653503, 65014.02478962914, 12103.092268080105, 12259.08941042455, 12352.135350525736, 12467.5012999479, 12562.937185314015, 11167.832482512931, 13669.906407487577, 14066.859331406555, 14347.56957291283, 14353.784693229818, 11904.239838937989, 13171.67070823237, 13316.533921312708, 13327.267000314689, 13843.377048032662, 12641.810372844599, 12898.806517902427, 13369.331533423485, 13680.179189248676, 13742.65643358924, 12428.75142497127, 12529.122961392615, 12788.360581970679, 12794.936025319814, 12813.295268760308, 9288.11762882525, 9927.354721943051, 10880.945595271973, 11030.779384412428, 11359.318440893572, 10919.672409827717, 11098.056665183227, 11269.830952535554, 11277.266977646426, 11402.600908968147], "value": 7183.3086979836025, "unit": "score"}], "name": "dromaeo_css", "value": 6989.925970347851, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 114.0, 119.0, 166.0, 161.0, 112.0, 114.0, 113.0, 114.0], "value": 124.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 109.0, 143.0, 176.0, 141.0, 112.0, 121.0, 138.0, 147.0, 109.0], "value": 134.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 183.0, 186.0, 184.0, 186.0, 185.0, 182.0, 182.0, 185.0, 190.0], "value": 184.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 58.0, 88.0, 59.0, 59.0, 58.0, 59.0, 81.0, 58.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 94.0, 70.0, 67.0, 68.0, 88.0, 67.0, 68.0, 90.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 132.0, 115.0, 86.0, 88.0, 85.0, 89.0, 87.0, 89.0, 85.0], "value": 94.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 96.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 81.0, 79.0, 80.0, 81.0, 84.0, 84.0, 84.0, 84.0], "value": 82.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 53.0, 56.0, 58.0, 57.0, 56.0, 56.0, 57.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 70.0, 71.0, 80.0, 73.0, 71.0, 70.0, 72.0, 71.0, 73.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 98.0, 103.0, 102.0, 77.0, 83.0, 83.0, 82.0, 84.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 145.0, 144.0, 138.0, 150.0, 160.0, 159.0, 250.0, 150.0], "value": 157.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 227.0, 201.0, 164.0, 163.0, 163.0, 167.0, 172.0, 164.0, 143.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 67.0, 70.0, 80.0, 86.0, 85.0, 85.0], "value": 74.2, "unit": "score"}], "name": "kraken", "value": 1477.1999999999998, "alertThreshold": 2.0}]} 15:32:32 INFO - Return code: 0 15:32:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:32:32 INFO - # TBPL SUCCESS # 15:32:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:32:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:32:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:32:32 INFO - 2017-01-14 15:32:32.883 screenresolution[1665:14519] starting screenresolution argv=screenresolution get 15:32:32 INFO - 2017-01-14 15:32:32.886 screenresolution[1665:14519] Display 0: 1600x1200x32@60 15:32:32 INFO - 2017-01-14 15:32:32.892 screenresolution[1666:14522] starting screenresolution argv=screenresolution list 15:32:32 INFO - Available Modes on Display 0 15:32:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:32:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:32:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:32:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:32:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:32:33 INFO - Intel Iris: 15:32:33 INFO - Chipset Model: Intel Iris 15:32:33 INFO - Type: GPU 15:32:33 INFO - Bus: Built-In 15:32:33 INFO - VRAM (Dynamic, Max): 1536 MB 15:32:33 INFO - Vendor: Intel (0x8086) 15:32:33 INFO - Device ID: 0x0a2e 15:32:33 INFO - Revision ID: 0x0009 15:32:33 INFO - Displays: 15:32:33 INFO - Display: 15:32:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:32:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:32:33 INFO - Display Serial Number: 1600x1200 60 15:32:33 INFO - Main Display: Yes 15:32:33 INFO - Mirror: Off 15:32:33 INFO - Online: Yes 15:32:33 INFO - Rotation: Supported 15:32:33 INFO - Return code: 0 15:32:33 INFO - Running post-action listener: _resource_record_post_action 15:32:33 INFO - [mozharness: 2017-01-14 23:32:33.144701Z] Finished run-tests step (success) 15:32:33 INFO - Running post-run listener: _resource_record_post_run 15:32:33 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 33422336; Write bytes: 662264320; Read time: 311; Write time: 1976 15:32:33 INFO - TinderboxPrint: CPU usage
53.4% 15:32:33 INFO - TinderboxPrint: I/O read bytes / time
33,422,336 / 311 15:32:33 INFO - TinderboxPrint: I/O write bytes / time
662,264,320 / 1,976 15:32:33 INFO - TinderboxPrint: CPU idle
2,258.9 (46.6%) 15:32:33 INFO - TinderboxPrint: CPU system
968.3 (20.0%) 15:32:33 INFO - TinderboxPrint: CPU user
1,618.4 (33.4%) 15:32:33 INFO - TinderboxPrint: Swap in / out
378,535,936 / 0 15:32:33 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 346464768; Write bytes: 345736704; Read time: 26761; Write time: 709 15:32:33 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 31415296; Write bytes: 309564416; Read time: 289; Write time: 1162 15:32:33 INFO - Running post-run listener: _upload_blobber_files 15:32:33 INFO - Blob upload gear active. 15:32:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:32:33 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 15:32:33 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'] 15:32:33 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 15:32:34 INFO - (blobuploader) - INFO - Open directory for files ... 15:32:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:32:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:32:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:34 INFO - (blobuploader) - INFO - Done attempting. 15:32:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:32:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:32:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:35 INFO - (blobuploader) - INFO - Done attempting. 15:32:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:32:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:32:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:37 INFO - (blobuploader) - INFO - Done attempting. 15:32:37 INFO - (blobuploader) - INFO - Iteration through files over. 15:32:37 INFO - Return code: 0 15:32:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:32:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:32:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2b2e70ce7e094ae89b3b96d2f759e0c3f2b14c7827a67f0a5aef84b36ce5623cbdf65015ce25541332ac3621bd55041ace00a37e8e4c814f1c2ad7aba3a7ced8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36ffdcf7a0c0f69a6da3742634cd8673a1a516801d9edc8f5be1077e21fb2224faec10fce34a31b62609957569fda6662e6674110cd69584680779f010f641b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/39c777ef82429cabd05ec76172cdb21051ac444f78712117007b2f53b22354742caea2635762a309bcd65c4c4c689a62c6459fe13f540f6310e265284298de93"} 15:32:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:32:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:32:37 INFO - Contents: 15:32:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2b2e70ce7e094ae89b3b96d2f759e0c3f2b14c7827a67f0a5aef84b36ce5623cbdf65015ce25541332ac3621bd55041ace00a37e8e4c814f1c2ad7aba3a7ced8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36ffdcf7a0c0f69a6da3742634cd8673a1a516801d9edc8f5be1077e21fb2224faec10fce34a31b62609957569fda6662e6674110cd69584680779f010f641b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/39c777ef82429cabd05ec76172cdb21051ac444f78712117007b2f53b22354742caea2635762a309bcd65c4c4c689a62c6459fe13f540f6310e265284298de93"} 15:32:37 INFO - Running post-run listener: copy_logs_to_upload_dir 15:32:37 INFO - Copying logs to upload dir... 15:32:37 INFO - mkdir: /builds/slave/test/build/upload/logs 15:32:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.030466 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-14 15:32:38.037254) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-14 15:32:38.040428) ========= 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.dW78mkqDca/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.HviZ8ix1Sk/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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/2b2e70ce7e094ae89b3b96d2f759e0c3f2b14c7827a67f0a5aef84b36ce5623cbdf65015ce25541332ac3621bd55041ace00a37e8e4c814f1c2ad7aba3a7ced8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36ffdcf7a0c0f69a6da3742634cd8673a1a516801d9edc8f5be1077e21fb2224faec10fce34a31b62609957569fda6662e6674110cd69584680779f010f641b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/39c777ef82429cabd05ec76172cdb21051ac444f78712117007b2f53b22354742caea2635762a309bcd65c4c4c689a62c6459fe13f540f6310e265284298de93"} build_url:https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010973 build_url: 'https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/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/2b2e70ce7e094ae89b3b96d2f759e0c3f2b14c7827a67f0a5aef84b36ce5623cbdf65015ce25541332ac3621bd55041ace00a37e8e4c814f1c2ad7aba3a7ced8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36ffdcf7a0c0f69a6da3742634cd8673a1a516801d9edc8f5be1077e21fb2224faec10fce34a31b62609957569fda6662e6674110cd69584680779f010f641b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/39c777ef82429cabd05ec76172cdb21051ac444f78712117007b2f53b22354742caea2635762a309bcd65c4c4c689a62c6459fe13f540f6310e265284298de93"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-14 15:32:38.074257) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 15:32:38.074585) ========= 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.dW78mkqDca/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.HviZ8ix1Sk/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.004993 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 15:32:38.122791) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-14 15:32:38.123120) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-14 15:32:38.123444) ========= ========= Total master_lag: 0.17 =========