builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0154 starttime: 1485102088.56 results: success (0) buildid: 20170122071525 builduid: ee84b0bbef8d4fafa14daf7d95270eeb revision: 24a81d93e07cc96300f8e1f5c69034dd4743bd63 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-22 08:21:28.557780) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-22 08:21:28.558200) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-22 08:21:28.558500) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-22 08:21:28.584807) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-22 08:21:28.585169) ========= 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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-22 08:21:28-- 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% 15.2M=0.001s 2017-01-22 08:21:29 (15.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.521246 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-22 08:21:29.130551) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-22 08:21:29.130836) ========= 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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.027117 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-22 08:21:29.180608) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-22 08:21:29.180929) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 24a81d93e07cc96300f8e1f5c69034dd4743bd63 --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 24a81d93e07cc96300f8e1f5c69034dd4743bd63 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BLouFN0QFJ/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.CbJSe8z4AR/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-22 08:21:29,269 truncating revision to first 12 chars 2017-01-22 08:21:29,269 Setting DEBUG logging. 2017-01-22 08:21:29,269 attempt 1/10 2017-01-22 08:21:29,269 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/24a81d93e07c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-22 08:21:30,301 unpacking tar archive at: mozilla-aurora-24a81d93e07c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.297886 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-22 08:21:30.502334) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-22 08:21:30.502644) ========= script_repo_revision: 24a81d93e07cc96300f8e1f5c69034dd4743bd63 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-22 08:21:30.503047) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-22 08:21:30.503319) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-22 08:21:30.522904) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-01-22 08:21:30.523331) ========= /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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:21:30 INFO - MultiFileLogger online at 20170122 08:21:30 in /builds/slave/test 08:21:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:21:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:21:30 INFO - {'append_to_log': False, 08:21:30 INFO - 'base_work_dir': '/builds/slave/test', 08:21:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:21:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:21:30 INFO - 'branch': 'Mozilla-Aurora', 08:21:30 INFO - 'buildbot_json_path': 'buildprops.json', 08:21:30 INFO - 'config_files': ('talos/mac_config.py',), 08:21:30 INFO - 'default_actions': ('clobber', 08:21:30 INFO - 'read-buildbot-config', 08:21:30 INFO - 'download-and-extract', 08:21:30 INFO - 'populate-webroot', 08:21:30 INFO - 'create-virtualenv', 08:21:30 INFO - 'install', 08:21:30 INFO - 'run-tests'), 08:21:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:21:30 INFO - 'download_minidump_stackwalk': True, 08:21:30 INFO - 'download_symbols': 'ondemand', 08:21:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:21:30 INFO - 'tooltool.py': '/tools/tooltool.py', 08:21:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:21:30 INFO - '/tools/misc-python/virtualenv.py')}, 08:21:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:21:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:21:30 INFO - 'installer_path': 'installer.exe', 08:21:30 INFO - 'log_level': 'info', 08:21:30 INFO - 'log_name': 'talos', 08:21:30 INFO - 'log_to_console': True, 08:21:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:21:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:21:30 INFO - 'opt_config_files': (), 08:21:30 INFO - 'pip_index': False, 08:21:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:21:30 INFO - 'cmd': ('bash', 08:21:30 INFO - '-c', 08:21:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:21:30 INFO - 'enabled': True, 08:21:30 INFO - 'halt_on_failure': False, 08:21:30 INFO - 'name': 'check_screen_resolution'},), 08:21:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:21:30 INFO - 'cmd': ('bash', 08:21:30 INFO - '-c', 08:21:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:21:30 INFO - 'enabled': True, 08:21:30 INFO - 'halt_on_failure': False, 08:21:30 INFO - 'name': 'check_screen_resolution'},), 08:21:30 INFO - 'run_cmd_checks_enabled': True, 08:21:30 INFO - 'sps_profile': False, 08:21:30 INFO - 'sps_profile_interval': 0, 08:21:30 INFO - 'suite': 'dromaeojs', 08:21:30 INFO - 'system_bits': '32', 08:21:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:21:30 INFO - 'title': 't-yosemite-r7-0154', 08:21:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:21:30 INFO - 'use_talos_json': True, 08:21:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:21:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:21:30 INFO - 'work_dir': 'build'} 08:21:30 INFO - [mozharness: 2017-01-22 16:21:30.726284Z] Running clobber step. 08:21:30 INFO - Running pre-action listener: _resource_record_pre_action 08:21:30 INFO - Running main action method: clobber 08:21:30 INFO - rmtree: /builds/slave/test/build 08:21:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:21:33 INFO - Running post-action listener: _resource_record_post_action 08:21:33 INFO - [mozharness: 2017-01-22 16:21:33.244550Z] Finished clobber step (success) 08:21:33 INFO - [mozharness: 2017-01-22 16:21:33.244707Z] Running read-buildbot-config step. 08:21:33 INFO - Running pre-action listener: _resource_record_pre_action 08:21:33 INFO - Running main action method: read_buildbot_config 08:21:33 INFO - Using buildbot properties: 08:21:33 INFO - { 08:21:33 INFO - "project": "", 08:21:33 INFO - "product": "firefox", 08:21:33 INFO - "script_repo_revision": "production", 08:21:33 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:21:33 INFO - "repository": "", 08:21:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:21:33 INFO - "buildid": "20170122071525", 08:21:33 INFO - "pgo_build": "False", 08:21:33 INFO - "basedir": "/builds/slave/test", 08:21:33 INFO - "buildnumber": 29, 08:21:33 INFO - "slavename": "t-yosemite-r7-0154", 08:21:33 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:21:33 INFO - "platform": "yosemite_r7", 08:21:33 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:21:33 INFO - "branch": "mozilla-aurora", 08:21:33 INFO - "repo_path": "releases/mozilla-aurora", 08:21:33 INFO - "revision": "24a81d93e07cc96300f8e1f5c69034dd4743bd63", 08:21:33 INFO - "stage_platform": "macosx64", 08:21:33 INFO - "builduid": "ee84b0bbef8d4fafa14daf7d95270eeb", 08:21:33 INFO - "slavebuilddir": "test" 08:21:33 INFO - } 08:21:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:21:33 INFO - Running post-action listener: _resource_record_post_action 08:21:33 INFO - [mozharness: 2017-01-22 16:21:33.248030Z] Finished read-buildbot-config step (success) 08:21:33 INFO - [mozharness: 2017-01-22 16:21:33.248143Z] Running download-and-extract step. 08:21:33 INFO - Running pre-action listener: _resource_record_pre_action 08:21:33 INFO - Running main action method: download_and_extract 08:21:33 INFO - mkdir: /builds/slave/test/build/tests 08:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:33 INFO - trying https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:21:33 INFO - Downloading https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:21:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:21:34 INFO - Downloaded 1199 bytes. 08:21:34 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:21:34 INFO - Using the following test package requirements: 08:21:34 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:21:34 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:34 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:21:34 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:34 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:21:34 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:34 INFO - u'jsshell-mac.zip'], 08:21:34 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:34 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:21:34 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:21:34 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:34 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:21:34 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:34 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:21:34 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:34 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:21:34 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:34 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:21:34 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:21:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:21:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:21:34 INFO - Fetch https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:21:35 INFO - Http code: 200 08:21:35 INFO - via: 1.1 dc553909528b8b63475c922dc07d8ba6.cloudfront.net (CloudFront) 08:21:35 INFO - x-amz-cf-id: UP2pRl8WEX2bGm88sxzRonJ5ANA5xgfV3qKDl3gjtMb1VUXEEzwbtQ== 08:21:35 INFO - x-amz-version-id: FwEAhJrD_FI_ojKIq0.7HpYMguCtslJ1 08:21:35 INFO - Expected file size: 16277513 08:21:35 INFO - Obtained file size: 16277513 08:21:42 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:21:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:21:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:21:42 INFO - Fetch https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:21:42 INFO - Http code: 200 08:21:42 INFO - via: 1.1 8e86372eaaa62fc64b70e7bcde725dd9.cloudfront.net (CloudFront) 08:21:42 INFO - x-amz-cf-id: 3owEJyTYSAKpTKCnspaYhnmi5-MsZNJ_95ioEFhCRofF_rUI7ciU-A== 08:21:42 INFO - x-amz-version-id: FwEAhJrD_FI_ojKIq0.7HpYMguCtslJ1 08:21:43 INFO - Expected file size: 16277513 08:21:43 INFO - Obtained file size: 16277513 08:21:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:21:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:21:49 INFO - Fetch https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:21:50 INFO - Http code: 200 08:21:50 INFO - via: 1.1 7db492e48a5d55351dcb787a8a14e113.cloudfront.net (CloudFront) 08:21:50 INFO - x-amz-cf-id: Yx0FX6Om9dSL4-m9vfttUBgaqmxryuIqfju0UGPsPrtEIw1mY4tlBA== 08:21:50 INFO - x-amz-version-id: Vtt.SMCVMqJCpfrUIUt4sjVkfGdN1MQP 08:21:50 INFO - Expected file size: 14402992 08:21:50 INFO - Obtained file size: 14402992 08:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:51 INFO - trying https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:21:51 INFO - Downloading https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:21:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:22:22 WARNING - Socket error when accessing https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg: The read operation timed out 08:22:22 INFO - retry: attempt #1 caught exception: The read operation timed out 08:22:22 INFO - retry: Failed, sleeping 30 seconds before retrying 08:22:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #2 08:22:57 INFO - Downloaded 110603269 bytes. 08:22:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:22:57 INFO - mkdir: /builds/slave/test/properties 08:22:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:22:57 INFO - Writing to file /builds/slave/test/properties/build_url 08:22:57 INFO - Contents: 08:22:57 INFO - build_url:https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:22:58 INFO - Running post-action listener: _resource_record_post_action 08:22:58 INFO - Running post-action listener: set_extra_try_arguments 08:22:58 INFO - [mozharness: 2017-01-22 16:22:58.036606Z] Finished download-and-extract step (success) 08:22:58 INFO - [mozharness: 2017-01-22 16:22:58.036735Z] Running populate-webroot step. 08:22:58 INFO - Running pre-action listener: _resource_record_pre_action 08:22:58 INFO - Running main action method: populate_webroot 08:22:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:22:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:22:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:22:58 INFO - '--tppagecycles', 08:22:58 INFO - '7'], 08:22:58 INFO - 'tests': ['tsvgm']}}, 08:22:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:22:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:22:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:22:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:22:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:22:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:22:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:58 INFO - 'talos_options': ['--disable-e10s'], 08:22:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:22:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:22:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:58 INFO - 'talos_options': ['--disable-e10s'], 08:22:58 INFO - 'tests': ['damp', 'tps']}, 08:22:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:58 INFO - 'tests': ['damp', 'tps']}, 08:22:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:22:58 INFO - 'tests': ['dromaeo_dom']}, 08:22:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:22:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:22:58 INFO - 'tests': ['basic_compositor_video']}, 08:22:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:22:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:22:58 INFO - 'tests': ['a11yr', 08:22:58 INFO - 'ts_paint', 08:22:58 INFO - 'tpaint', 08:22:58 INFO - 'sessionrestore', 08:22:58 INFO - 'sessionrestore_no_auto_restore', 08:22:58 INFO - 'tabpaint']}, 08:22:58 INFO - 'other-e10s': {'tests': ['a11yr', 08:22:58 INFO - 'ts_paint', 08:22:58 INFO - 'tpaint', 08:22:58 INFO - 'sessionrestore', 08:22:58 INFO - 'sessionrestore_no_auto_restore', 08:22:58 INFO - 'tabpaint']}, 08:22:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:22:58 INFO - 'ts_paint', 08:22:58 INFO - 'tpaint', 08:22:58 INFO - 'sessionrestore', 08:22:58 INFO - 'sessionrestore_no_auto_restore', 08:22:58 INFO - 'tabpaint']}, 08:22:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:22:58 INFO - 'ts_paint', 08:22:58 INFO - 'tpaint', 08:22:58 INFO - 'sessionrestore', 08:22:58 INFO - 'sessionrestore_no_auto_restore', 08:22:58 INFO - 'tabpaint']}, 08:22:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:22:58 INFO - 'tests': ['a11yr', 08:22:58 INFO - 'ts_paint', 08:22:58 INFO - 'tpaint', 08:22:58 INFO - 'sessionrestore', 08:22:58 INFO - 'sessionrestore_no_auto_restore', 08:22:58 INFO - 'tabpaint']}, 08:22:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:22:58 INFO - 'tests': ['a11yr', 08:22:58 INFO - 'ts_paint', 08:22:58 INFO - 'tpaint', 08:22:58 INFO - 'sessionrestore', 08:22:58 INFO - 'sessionrestore_no_auto_restore', 08:22:58 INFO - 'tabpaint']}, 08:22:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:22:58 INFO - 'tests': ['tsvgx', 08:22:58 INFO - 'tsvgr_opacity', 08:22:58 INFO - 'tart', 08:22:58 INFO - 'tscrollx', 08:22:58 INFO - 'cart']}, 08:22:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:22:58 INFO - 'tsvgr_opacity', 08:22:58 INFO - 'tart', 08:22:58 INFO - 'tscrollx', 08:22:58 INFO - 'cart']}, 08:22:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:58 INFO - 'talos_options': ['--disable-e10s'], 08:22:58 INFO - 'tests': ['tp5o']}, 08:22:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:58 INFO - 'tests': ['tp5o']}, 08:22:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:58 INFO - 'talos_options': ['--disable-e10s', 08:22:58 INFO - '--xperf_path', 08:22:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:22:58 INFO - 'tests': ['tp5n']}, 08:22:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:58 INFO - 'talos_options': ['--xperf_path', 08:22:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:22:58 INFO - 'tests': ['tp5n']}}, 08:22:58 INFO - 'talos.zip': {'path': '', 08:22:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:22:58 INFO - Running post-action listener: _resource_record_post_action 08:22:58 INFO - [mozharness: 2017-01-22 16:22:58.055062Z] Finished populate-webroot step (success) 08:22:58 INFO - [mozharness: 2017-01-22 16:22:58.055163Z] Running create-virtualenv step. 08:22:58 INFO - Running pre-action listener: _resource_record_pre_action 08:22:58 INFO - Running main action method: create_virtualenv 08:22:58 INFO - Creating virtualenv /builds/slave/test/build/venv 08:22:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:22:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:22:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:22:58 INFO - Using real prefix '/tools/python27' 08:22:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:22:58 INFO - Installing distribute.............................................................................................................................................................................................done. 08:23:01 INFO - Installing pip.................done. 08:23:01 INFO - Return code: 0 08:23:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:23:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:23:02 INFO - Reading from file tmpfile_stdout 08:23:02 INFO - Output received: 08:23:02 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:23:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:23: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')]} 08:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23: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')]} 08:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10223b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa31b5a4f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204fd30>, '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 0x101eb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102047b20>, '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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23: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', 'pip>=1.5'] in /builds/slave/test/build 08:23: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 pip>=1.5 08:23:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BLouFN0QFJ/Render', 08:23:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:02 INFO - 'HOME': '/Users/cltbld', 08:23:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:02 INFO - 'LOGNAME': 'cltbld', 08:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:02 INFO - 'MOZ_NO_REMOTE': '1', 08:23:02 INFO - 'NO_EM_RESTART': '1', 08:23:02 INFO - 'PAGER': '/bin/cat', 08:23:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:02 INFO - 'PWD': '/builds/slave/test', 08:23:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:02 INFO - 'SHELL': '/bin/bash', 08:23:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CbJSe8z4AR/Listeners', 08:23:02 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:23:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:02 INFO - 'USER': 'cltbld', 08:23:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:02 INFO - 'XPC_FLAGS': '0x0', 08:23:02 INFO - 'XPC_SERVICE_NAME': '0', 08:23:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:23:02 INFO - Cleaning up... 08:23:02 INFO - Return code: 0 08:23:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:23: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')]} 08:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23: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')]} 08:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23: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', '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 0x10223b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa31b5a4f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204fd30>, '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 0x101eb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102047b20>, '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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:23: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 psutil>=3.1.1 08:23:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BLouFN0QFJ/Render', 08:23:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:02 INFO - 'HOME': '/Users/cltbld', 08:23:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:02 INFO - 'LOGNAME': 'cltbld', 08:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:02 INFO - 'MOZ_NO_REMOTE': '1', 08:23:02 INFO - 'NO_EM_RESTART': '1', 08:23:02 INFO - 'PAGER': '/bin/cat', 08:23:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:02 INFO - 'PWD': '/builds/slave/test', 08:23:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:02 INFO - 'SHELL': '/bin/bash', 08:23:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CbJSe8z4AR/Listeners', 08:23:02 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:23:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:02 INFO - 'USER': 'cltbld', 08:23:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:02 INFO - 'XPC_FLAGS': '0x0', 08:23:02 INFO - 'XPC_SERVICE_NAME': '0', 08:23:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:02 INFO - Downloading/unpacking psutil>=3.1.1 08:23: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 08:23: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 08:23: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 08:23: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 08:23: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 08:23: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 08:23:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:23:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:23:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:23:06 INFO - Installing collected packages: psutil 08:23:06 INFO - Running setup.py install for psutil 08:23:06 INFO - building 'psutil._psutil_osx' extension 08:23:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:23:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:23:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:23:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:23:07 INFO - building 'psutil._psutil_posix' extension 08:23:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:23:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:23:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:23:07 INFO - ^ 08:23:07 INFO - 1 warning generated. 08:23:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:23:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:23:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:23:07 INFO - Successfully installed psutil 08:23:07 INFO - Cleaning up... 08:23:07 INFO - Return code: 0 08:23:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:07 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 0x10223b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa31b5a4f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204fd30>, '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 0x101eb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102047b20>, '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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:23:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:23:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BLouFN0QFJ/Render', 08:23:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:07 INFO - 'HOME': '/Users/cltbld', 08:23:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:07 INFO - 'LOGNAME': 'cltbld', 08:23:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:07 INFO - 'MOZ_NO_REMOTE': '1', 08:23:07 INFO - 'NO_EM_RESTART': '1', 08:23:07 INFO - 'PAGER': '/bin/cat', 08:23:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:07 INFO - 'PWD': '/builds/slave/test', 08:23:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:07 INFO - 'SHELL': '/bin/bash', 08:23:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CbJSe8z4AR/Listeners', 08:23:07 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:23:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:07 INFO - 'USER': 'cltbld', 08:23:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:07 INFO - 'XPC_FLAGS': '0x0', 08:23:07 INFO - 'XPC_SERVICE_NAME': '0', 08:23:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:23: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 08:23: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 08:23: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 08:23: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 08:23: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 08:23: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 08:23:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:23:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:23:10 INFO - Installing collected packages: mozsystemmonitor 08:23:10 INFO - Running setup.py install for mozsystemmonitor 08:23:10 INFO - Successfully installed mozsystemmonitor 08:23:10 INFO - Cleaning up... 08:23:11 INFO - Return code: 0 08:23:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:11 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 0x10223b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa31b5a4f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204fd30>, '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 0x101eb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102047b20>, '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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:23:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BLouFN0QFJ/Render', 08:23:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:11 INFO - 'HOME': '/Users/cltbld', 08:23:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:11 INFO - 'LOGNAME': 'cltbld', 08:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:11 INFO - 'MOZ_NO_REMOTE': '1', 08:23:11 INFO - 'NO_EM_RESTART': '1', 08:23:11 INFO - 'PAGER': '/bin/cat', 08:23:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:11 INFO - 'PWD': '/builds/slave/test', 08:23:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:11 INFO - 'SHELL': '/bin/bash', 08:23:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CbJSe8z4AR/Listeners', 08:23:11 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:23:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:11 INFO - 'USER': 'cltbld', 08:23:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:11 INFO - 'XPC_FLAGS': '0x0', 08:23:11 INFO - 'XPC_SERVICE_NAME': '0', 08:23:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:11 INFO - Downloading/unpacking jsonschema==2.5.1 08:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:23:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:14 INFO - Downloading functools32-3.2.3-2.tar.gz 08:23:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:23:14 INFO - warning: no files found matching '*.txt' 08:23:14 INFO - no previously-included directories found matching 'build' 08:23:14 INFO - no previously-included directories found matching 'dist' 08:23:14 INFO - no previously-included directories found matching '.git*' 08:23:14 INFO - Installing collected packages: jsonschema, functools32 08:23:14 INFO - Running setup.py install for functools32 08:23:14 INFO - warning: no files found matching '*.txt' 08:23:14 INFO - no previously-included directories found matching 'build' 08:23:14 INFO - no previously-included directories found matching 'dist' 08:23:14 INFO - no previously-included directories found matching '.git*' 08:23:14 INFO - Successfully installed jsonschema functools32 08:23:14 INFO - Cleaning up... 08:23:14 INFO - Return code: 0 08:23:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:14 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 0x10223b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa31b5a4f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204fd30>, '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 0x101eb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102047b20>, '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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:23:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:23:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BLouFN0QFJ/Render', 08:23:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:14 INFO - 'HOME': '/Users/cltbld', 08:23:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:14 INFO - 'LOGNAME': 'cltbld', 08:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:14 INFO - 'MOZ_NO_REMOTE': '1', 08:23:14 INFO - 'NO_EM_RESTART': '1', 08:23:14 INFO - 'PAGER': '/bin/cat', 08:23:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:14 INFO - 'PWD': '/builds/slave/test', 08:23:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:14 INFO - 'SHELL': '/bin/bash', 08:23:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CbJSe8z4AR/Listeners', 08:23:14 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:23:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:14 INFO - 'USER': 'cltbld', 08:23:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:14 INFO - 'XPC_FLAGS': '0x0', 08:23:14 INFO - 'XPC_SERVICE_NAME': '0', 08:23:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:23:14 INFO - Cleaning up... 08:23:14 INFO - Return code: 0 08:23:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:14 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 0x10223b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa31b5a4f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204fd30>, '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 0x101eb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102047b20>, '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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:23:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:23:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BLouFN0QFJ/Render', 08:23:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:14 INFO - 'HOME': '/Users/cltbld', 08:23:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:14 INFO - 'LOGNAME': 'cltbld', 08:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:14 INFO - 'MOZ_NO_REMOTE': '1', 08:23:14 INFO - 'NO_EM_RESTART': '1', 08:23:14 INFO - 'PAGER': '/bin/cat', 08:23:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:14 INFO - 'PWD': '/builds/slave/test', 08:23:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:14 INFO - 'SHELL': '/bin/bash', 08:23:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CbJSe8z4AR/Listeners', 08:23:14 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:23:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:14 INFO - 'USER': 'cltbld', 08:23:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:14 INFO - 'XPC_FLAGS': '0x0', 08:23:14 INFO - 'XPC_SERVICE_NAME': '0', 08:23:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:15 INFO - Downloading/unpacking blobuploader==1.2.4 08:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:17 INFO - Downloading blobuploader-1.2.4.tar.gz 08:23:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:23:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:23: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 08:23: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 08:23: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 08:23: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 08:23: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 08:23: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 08:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:23:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:19 INFO - Downloading docopt-0.6.1.tar.gz 08:23:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:23:19 INFO - Installing collected packages: blobuploader, requests, docopt 08:23:19 INFO - Running setup.py install for blobuploader 08:23:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:23:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:23:19 INFO - Running setup.py install for requests 08:23:19 INFO - Running setup.py install for docopt 08:23:19 INFO - Successfully installed blobuploader requests docopt 08:23:19 INFO - Cleaning up... 08:23:20 INFO - Return code: 0 08:23:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:23: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')]} 08:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23: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')]} 08:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:20 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 0x10223b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa31b5a4f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204fd30>, '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 0x101eb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102047b20>, '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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BLouFN0QFJ/Render', 08:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:20 INFO - 'HOME': '/Users/cltbld', 08:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:20 INFO - 'LOGNAME': 'cltbld', 08:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:20 INFO - 'MOZ_NO_REMOTE': '1', 08:23:20 INFO - 'NO_EM_RESTART': '1', 08:23:20 INFO - 'PAGER': '/bin/cat', 08:23:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:20 INFO - 'PWD': '/builds/slave/test', 08:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:20 INFO - 'SHELL': '/bin/bash', 08:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CbJSe8z4AR/Listeners', 08:23:20 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:20 INFO - 'USER': 'cltbld', 08:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:20 INFO - 'XPC_FLAGS': '0x0', 08:23:20 INFO - 'XPC_SERVICE_NAME': '0', 08:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:23:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-OsoFog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:23:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-9jeeDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:23:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-8uKxtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:23:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-5DNuUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:23:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-tGX6vn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:23:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Xq53f0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:23:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-COdwrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:23:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-6IxepY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:23:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-z6BMku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:23:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-noRDF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:23:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-67MRdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:23:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-FWXLpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:23:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-yKH_hS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:23:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-bKeuZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:23:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-FH3LuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:23:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-9MavF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:23:22 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-bM17zC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:23:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:23:22 INFO - Running setup.py install for manifestparser 08:23:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:23:22 INFO - Running setup.py install for mozcrash 08:23:22 INFO - Running setup.py install for mozdebug 08:23:22 INFO - Running setup.py install for mozdevice 08:23:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:23:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:23:22 INFO - Running setup.py install for mozfile 08:23:22 INFO - Running setup.py install for mozhttpd 08:23:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:23:22 INFO - Running setup.py install for mozinfo 08:23:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:23:23 INFO - Running setup.py install for mozInstall 08:23:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:23:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:23:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:23:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:23:23 INFO - Running setup.py install for mozleak 08:23:23 INFO - Running setup.py install for mozlog 08:23:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:23:23 INFO - Running setup.py install for moznetwork 08:23:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:23:23 INFO - Running setup.py install for mozprocess 08:23:23 INFO - Running setup.py install for mozprofile 08:23:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:23:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:23:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:23:24 INFO - Running setup.py install for mozrunner 08:23:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:23:24 INFO - Running setup.py install for mozscreenshot 08:23:24 INFO - Running setup.py install for moztest 08:23:24 INFO - Running setup.py install for mozversion 08:23:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:23:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:23:24 INFO - Cleaning up... 08:23:24 INFO - Return code: 0 08:23:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:23:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:24 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 0x10223b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa31b5a4f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204fd30>, '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 0x101eb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102047b20>, '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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:23:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:23:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BLouFN0QFJ/Render', 08:23:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:24 INFO - 'HOME': '/Users/cltbld', 08:23:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:24 INFO - 'LOGNAME': 'cltbld', 08:23:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:24 INFO - 'MOZ_NO_REMOTE': '1', 08:23:24 INFO - 'NO_EM_RESTART': '1', 08:23:24 INFO - 'PAGER': '/bin/cat', 08:23:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:24 INFO - 'PWD': '/builds/slave/test', 08:23:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:24 INFO - 'SHELL': '/bin/bash', 08:23:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CbJSe8z4AR/Listeners', 08:23:24 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:23:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:24 INFO - 'USER': 'cltbld', 08:23:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:24 INFO - 'XPC_FLAGS': '0x0', 08:23:24 INFO - 'XPC_SERVICE_NAME': '0', 08:23:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:23:24 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-UXrLDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:23:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-n1nfo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:23:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-7R7Y2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:23:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-4pa4bN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:23:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-A9tSjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:23:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-6qZI5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:23:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-pUOqkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:23:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-WKUixz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:23:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-7NABTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:23:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-oC6qAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:23:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-oiHpU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:23:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-QJTDDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:23:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-eg2cjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:23:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-nNSJXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:23:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-512CTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:23:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-2sm8Qo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:23:26 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)) 08:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:23:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-X4HnSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:23:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:29 INFO - Downloading blessings-1.6.tar.gz 08:23:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:23:29 INFO - Installing collected packages: blessings 08:23:29 INFO - Running setup.py install for blessings 08:23:29 INFO - Successfully installed blessings 08:23:29 INFO - Cleaning up... 08:23:29 INFO - Return code: 0 08:23:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:23:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:23:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:23:30 INFO - Reading from file tmpfile_stdout 08:23:30 INFO - Current package versions: 08:23:30 INFO - blessings == 1.6 08:23:30 INFO - blobuploader == 1.2.4 08:23:30 INFO - docopt == 0.6.1 08:23:30 INFO - functools32 == 3.2.3-2 08:23:30 INFO - jsonschema == 2.5.1 08:23:30 INFO - manifestparser == 1.1 08:23:30 INFO - mozInstall == 1.12 08:23:30 INFO - mozcrash == 1.0 08:23:30 INFO - mozdebug == 0.1 08:23:30 INFO - mozdevice == 0.48 08:23:30 INFO - mozfile == 1.2 08:23:30 INFO - mozhttpd == 0.7 08:23:30 INFO - mozinfo == 0.9 08:23:30 INFO - mozleak == 0.1 08:23:30 INFO - mozlog == 3.4 08:23:30 INFO - moznetwork == 0.27 08:23:30 INFO - mozprocess == 0.23 08:23:30 INFO - mozprofile == 0.28 08:23:30 INFO - mozrunner == 6.13 08:23:30 INFO - mozscreenshot == 0.1 08:23:30 INFO - mozsystemmonitor == 0.3 08:23:30 INFO - moztest == 0.8 08:23:30 INFO - mozversion == 1.4 08:23:30 INFO - psutil == 3.1.1 08:23:30 INFO - requests == 1.2.3 08:23:30 INFO - wsgiref == 0.1.2 08:23:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:30 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 0x10223b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa31b5a4f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204fd30>, '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 0x101eb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102047b20>, '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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BLouFN0QFJ/Render', 08:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:30 INFO - 'HOME': '/Users/cltbld', 08:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:30 INFO - 'LOGNAME': 'cltbld', 08:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:30 INFO - 'MOZ_NO_REMOTE': '1', 08:23:30 INFO - 'NO_EM_RESTART': '1', 08:23:30 INFO - 'PAGER': '/bin/cat', 08:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:30 INFO - 'PWD': '/builds/slave/test', 08:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:30 INFO - 'SHELL': '/bin/bash', 08:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CbJSe8z4AR/Listeners', 08:23:30 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:30 INFO - 'USER': 'cltbld', 08:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:30 INFO - 'XPC_FLAGS': '0x0', 08:23:30 INFO - 'XPC_SERVICE_NAME': '0', 08:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:23:30 INFO - Cleaning up... 08:23:30 INFO - Return code: 0 08:23:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:30 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 0x10223b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa31b5a4f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204fd30>, '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 0x101eb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102047b20>, '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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BLouFN0QFJ/Render', 08:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:30 INFO - 'HOME': '/Users/cltbld', 08:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:30 INFO - 'LOGNAME': 'cltbld', 08:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:30 INFO - 'MOZ_NO_REMOTE': '1', 08:23:30 INFO - 'NO_EM_RESTART': '1', 08:23:30 INFO - 'PAGER': '/bin/cat', 08:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:30 INFO - 'PWD': '/builds/slave/test', 08:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:30 INFO - 'SHELL': '/bin/bash', 08:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CbJSe8z4AR/Listeners', 08:23:30 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:30 INFO - 'USER': 'cltbld', 08:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:30 INFO - 'XPC_FLAGS': '0x0', 08:23:30 INFO - 'XPC_SERVICE_NAME': '0', 08:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:23:30 INFO - Cleaning up... 08:23:30 INFO - Return code: 0 08:23:30 INFO - Running post-action listener: _resource_record_post_action 08:23:30 INFO - Running post-action listener: _start_resource_monitoring 08:23:30 INFO - Starting resource monitoring. 08:23:30 INFO - [mozharness: 2017-01-22 16:23:30.628529Z] Finished create-virtualenv step (success) 08:23:30 INFO - [mozharness: 2017-01-22 16:23:30.629537Z] Running install step. 08:23:30 INFO - Running pre-action listener: _resource_record_pre_action 08:23:30 INFO - Running main action method: install 08:23:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:23:30 INFO - Reading from file tmpfile_stdout 08:23:30 INFO - Detecting whether we're running mozinstall >=1.0... 08:23:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:23:30 INFO - Reading from file tmpfile_stdout 08:23:30 INFO - Output received: 08:23:30 INFO - Usage: mozinstall [options] installer 08:23:30 INFO - Options: 08:23:30 INFO - -h, --help show this help message and exit 08:23:30 INFO - -d DEST, --destination=DEST 08:23:30 INFO - Directory to install application into. [default: 08:23:30 INFO - "/builds/slave/test"] 08:23:30 INFO - --app=APP Application being installed. [default: firefox] 08:23:30 INFO - mkdir: /builds/slave/test/build/application 08:23:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:24:02 INFO - Reading from file tmpfile_stdout 08:24:02 INFO - Output received: 08:24:02 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:24:02 INFO - Running post-action listener: _resource_record_post_action 08:24:02 INFO - [mozharness: 2017-01-22 16:24:02.621186Z] Finished install step (success) 08:24:02 INFO - [mozharness: 2017-01-22 16:24:02.621337Z] Running run-tests step. 08:24:02 INFO - Running pre-action listener: _resource_record_pre_action 08:24:02 INFO - Running main action method: run_tests 08:24:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:24:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:24:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:24:02 INFO - 2017-01-22 08:24:02.635 screenresolution[1651:15244] starting screenresolution argv=screenresolution get 08:24:02 INFO - 2017-01-22 08:24:02.638 screenresolution[1651:15244] Display 0: 1600x1200x32@60 08:24:02 INFO - 2017-01-22 08:24:02.644 screenresolution[1652:15246] starting screenresolution argv=screenresolution list 08:24:02 INFO - Available Modes on Display 0 08:24:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:24:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:24:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:24:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:24:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:24:02 INFO - Intel Iris: 08:24:02 INFO - Chipset Model: Intel Iris 08:24:02 INFO - Type: GPU 08:24:02 INFO - Bus: Built-In 08:24:02 INFO - VRAM (Dynamic, Max): 1536 MB 08:24:02 INFO - Vendor: Intel (0x8086) 08:24:02 INFO - Device ID: 0x0a2e 08:24:02 INFO - Revision ID: 0x0009 08:24:02 INFO - Displays: 08:24:02 INFO - Display: 08:24:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:24:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:24:02 INFO - Display Serial Number: 1600x1200 60 08:24:02 INFO - Main Display: Yes 08:24:02 INFO - Mirror: Off 08:24:02 INFO - Online: Yes 08:24:02 INFO - Rotation: Supported 08:24:02 INFO - Return code: 0 08:24:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:24:02 INFO - Python 2.7.3 08:24:02 INFO - Return code: 0 08:24:02 INFO - grabbing minidump binary from tooltool 08:24: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')]} 08:24:02 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 0x10204fd30>, '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 0x101eb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102047b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:24:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:24:02 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:24:02 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:24:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:24:03 INFO - Return code: 0 08:24:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:24:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:24:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:24:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:24:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:24:03 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/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:24:03 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/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0154 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:24:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BLouFN0QFJ/Render', 08:24:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:03 INFO - 'HOME': '/Users/cltbld', 08:24:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:03 INFO - 'LOGNAME': 'cltbld', 08:24:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:24:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:24:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:03 INFO - 'MOZ_NO_REMOTE': '1', 08:24:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:24:03 INFO - 'NO_EM_RESTART': '1', 08:24:03 INFO - 'PAGER': '/bin/cat', 08:24:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:03 INFO - 'PWD': '/builds/slave/test', 08:24:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:24:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:03 INFO - 'SHELL': '/bin/bash', 08:24:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CbJSe8z4AR/Listeners', 08:24:03 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:24:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:03 INFO - 'USER': 'cltbld', 08:24:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:03 INFO - 'XPC_FLAGS': '0x0', 08:24:03 INFO - 'XPC_SERVICE_NAME': '0', 08:24:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:03 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/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:24:03 INFO - mozversion application_buildid: 20170122071525 08:24:03 INFO - mozversion application_changeset: 24a81d93e07cc96300f8e1f5c69034dd4743bd63 08:24:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:24:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:24:03 INFO - mozversion application_name: Firefox 08:24:03 INFO - mozversion application_remotingname: firefox-dev 08:24:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:24:03 INFO - mozversion application_vendor: Mozilla 08:24:03 INFO - mozversion application_version: 52.0a2 08:24:03 INFO - mozversion platform_buildid: 20170122071525 08:24:03 INFO - mozversion platform_changeset: 24a81d93e07cc96300f8e1f5c69034dd4743bd63 08:24:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:24:03 INFO - mozversion platform_version: 52.0a2 08:24:03 INFO - using testdate: 1485102243 08:24:03 INFO - actual date: 1485102243 08:24:03 INFO - starting webserver on 'localhost:49247' 08:24:03 INFO - SUITE-START | Running 2 tests 08:24:03 INFO - TEST-START | dromaeo_css 08:24:03 INFO - operating with platform_type : mac_ 08:24:03 INFO - Initialising browser for dromaeo_css test... 08:24:03 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpm793Jk/profile http://localhost:49247/getInfo.html) 08:24:05 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 08:24:05 INFO - PROCESS | 1662 | colorDepth:24 08:24:05 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 08:24:05 INFO - PROCESS | 1662 | __metrics 08:24:06 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:24:06 INFO - PROCESS | 1662 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:24:06 INFO - TEST-INFO | 1662: exit 0 08:24:06 INFO - Browser initialized. 08:24:06 INFO - Running cycle 1/1 for dromaeo_css test... 08:24:06 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpm793Jk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:24:19 INFO - PROCESS | 1665 | RSS: Main: 221999104 08:24:19 INFO - PROCESS | 1665 | 08:27:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 08:27:24 INFO - PROCESS | 1665 | RSS: Main: 316411904 08:27:24 INFO - PROCESS | 1665 | 08:30:26 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 08:30:27 INFO - PROCESS | 1665 | RSS: Main: 346087424 08:30:27 INFO - PROCESS | 1665 | 08:33:29 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 08:33:30 INFO - PROCESS | 1665 | RSS: Main: 315944960 08:33:30 INFO - PROCESS | 1665 | 08:36:32 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 08:36:33 INFO - PROCESS | 1665 | RSS: Main: 326389760 08:36:33 INFO - PROCESS | 1665 | 08:39:36 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 08:39:37 INFO - PROCESS | 1665 | RSS: Main: 305987584 08:39:37 INFO - PROCESS | 1665 | 08:42:40 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 08:42:40 INFO - PROCESS | 1665 | RSS: Main: 429936640 08:42:40 INFO - PROCESS | 1665 | 08:42:40 INFO - PROCESS | 1665 | __start_tp_report 08:42:40 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 08:42:40 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 08:42:40 INFO - PROCESS | 1665 | |i|pagename|runs| 08:42:40 INFO - PROCESS | 1665 | |0;dojo.html;3269.4115059289325;3273.721733652641;3289.4901290299995;3311.0066979906023;3331.8167500787463;5538.58460615454;5581.107022876341;5581.218629391883;5582.916256256154;5592.273004509408;5575.275214222155;5654.293213348332;5666.036773748462;5675.489205971462;5717.914231286523;5115.7697903594335;5129.871753206181;5143.588512918957;5181.5077567631015;5202.791888324463;5098.821541246057;5111.821086261982;5120.078385890538;5121.027004869082;5308;5177.119889618773;5221.791128354861;5256.159014557672;5259.368875734898;5269.552088072518;5224.52979231871;5230.084735171368;5234.476552344754;5247.553957913563;5269.2886460327745;4990.65065445419;5119;5157.767900444489;5162.432132465426;5164.406093299281;5539.307586551681;5557.166425036236;5585.162225666142;5635.351934527541;5651.519620832193;6105.961986462312;6124.7243874025335;6128.601640893372;6197.349278325801;6198.81403557894;6249.6875156242;6277.309495955441;6281;6284.780032698834;6289.748410063592;5542.528885044736;5607.607467477278;5667.064934285797;5770;5790.334111577223;6322.893493638595;6324.891169386798;6336.904946425715;6357.904631430532;6362.395572420705;6064.48451881595;6089.604175728564;6091.0558863376245;6094.634321940698;6107.603005804608;6073.908891366633;6129.297663866018;6129.325774164839;6130.969345153246;6144.539159563051;6092.360302168208;6140.04415716754;6141.560725993369;6193.102000209944;6195.132681424604;6102.6338419694075;6201.565890387629;6206.379362063757;6208.565400422018;6215;4891.266310053449;4904.60763138948;4910.533499317559;4917.0903382874285;4938.8271410501175;4330.501992270866;4355.412019377343;4358.629516491071;4383.583559561836;4448.310511870665;5726.713664316768;5730.627509211888;5738.741756620962;5742.885142297131;5781.566382521328;6223.439890409885;6257.624542527463;6260.436560709558;6262.1546091278005;6270.184875966186;4887.169181239198;5575.996320662239;5620.690862002629;5626.324841019022;5669.177969194444;5345.679259244458;5346.786128554814;5349.277847490539;5370.355557333145;5391.865203369947;6267;6283.591566548068;6327.873442531216;6336.841578960379;6349.904751428732;5901.940980590139;6136.274005701014;6146.0473626587955;6268.341824108586;6274;6247.468965137832;6301.684915754286;6307.968460157854;6310.6844657766005;6320.841978950563;6018.699065046642;6139.693015349126;6144.969275153596;6148.508119350352;6158.291796443466;6066.666333351708;6116.944513560587;6230.532710046855;6235.75056997715;6249.812505624838;5982;5993.011153159854;6033.577649564489;6084.939150608438;6108.11432342299;6052.273727152769;6053.545984051302;6060.303065147565;6060.878782424415;6081.665508397081;5691.658500489983;5815.069588865761;5831.562632802472;5859.414058594107;5887.8233652990475;5908.911366329423;5947.910781338197;5974.40255974399;6012;6043.54673399488;5891.410858914074;5898;5898.8230353089475;5908.822735317947;5915.970420147872 08:42:40 INFO - PROCESS | 1665 | |1;ext.html;498.5214194373401;502.84914525642296;506.7508591322391;515.4150039704936;522.1176212201377;30229.941952031684;32070.99999999997;32076.518852217236;39084.41373379402;40186;42103.78948105263;42451.363229551505;42453;42488;42505.14989700212;9378.859317110231;9522.80954380912;9527.142557169853;9531.666391676292;9535.094166054214;8482.618282177316;8687.261582765472;9370.906290937106;11197.66407007787;11480.081593472543;11435.970762631361;11480.942595287011;11527.711807204803;11531.365774882379;11573.95834374906;11351.43242837859;11396.658100256922;11410.030147437477;11417.200795944287;11424.371659558763;8434.915650843473;8663.870041949376;11116.166287528467;11345.978861902386;11475.655730328104;39894.800525997474;40076.599234007575;40152;40230.39654405157;40246;2970.12381347503;3181.109289398972;3247.6914693104354;3307.090550098742;3486.477028445728;29569.70430295691;29938.401231975455;30399;30795.922142724896;31035.844820775976;3174.2699179188917;3183.8248896310693;3189.8388118673724;3202.727768139711;3221.4523530999786;469404;470352;470643.6467817673;471628;473467;61055.694721527;61440;61541.07688384678;61543.69228153831;61544;64525.677371612845;64749;64754.35245647483;64771;64804;15731.528054158394;16240.350385984664;16252.83747162537;16256.837431625532;16261.268242929096;12467.251964881954;12519.874801251872;12529.624111276677;12549.309787961749;12571;15448.768268475982;15809.288582013836;15836.12901290417;16076.356945722275;16114.919425402797;5993.430624090704;6329.968350158219;7016.649167541603;7036.472264580177;7041.556480921399;831.2850948184556;930.9906900931039;968.6077138758814;988.6292640259902;1019.5819713917407;28602.855885764337;29011.564826527196;29081.418371632448;29516;30016.39967200644;890.7906641939139;934.0892629686138;1058.9258751887294;1090.373035504588;1109.7724966381872;14722.926385368004;15128.39486420553;15257.389704411698;15475.613109672347;15593.610159746097;637544;642880;642956;643767;645398;49263.261051084264;50089.49910500849;50139.74930125399;54379.72810135924;55680;1269.9301538415107;1322.2066759944148;1344.5697376839319;1358.1443690475135;1365.863413658646;23689.28932132039;27923.18499297539;29949.70050299556;30064.549031764105;30763.692363076978;51026.74486627692;51608.74195628998;51642.48357516527;51647.48352516577;51852;419986;427491;435664;435949.82025089674;436549;21507.78492215058;23283.534329313658;25375.731213439623;25386.365340866625;25440;44565.55434445614;44675;44851.775741121084;45105.548944510134;45187;33134.17164570905;33867.66132338645;37023;37212.06969825776;37364.06589835276;36278.27443451169;36740.26519469649;37189;37250.8137459311;38306.42540361953;29835.850820746626;36059;36550.4517432244;37061.44407833939;37376.62623373731;31905;33288.18960366519;33791;34048.14879628029;34144;25179.49641007206;25438.364040899127;25445.61831572565;25584;25637.743622563536 08:42:40 INFO - PROCESS | 1665 | |2;jquery.html;306.5876396247047;376.61585183113226;376.71369758983184;388.57839244419813;405.663299461447;14710.190939498323;14826;15747.448839290628;20097;20702.585948281063;19283.325083622076;20150.99245037746;20186.49533761657;20288.391348259538;20532;5682.573806964473;5934.020886553713;5934.762609495615;5943.702814859262;5962.910556341659;4583.266677331628;5775.624584402021;6554.8689026219445;6586.9670651646675;6591.93408065918;6385.968070159666;6429.935700643004;6515.967420162892;6535.0524173994745;6543.672816359187;6427.8393040173905;6441.323661015573;6447.903281450758;6453.612783233021;6461.709223084972;5116.820911268088;5400.405955344909;5439.700816455093;5492.780288788444;5653.886922261573;13146.342682865818;13428.462861485528;13439.798403023962;22986.77013229863;24692.506149876903;2109.778473260301;2352.494213744036;2430.708315002211;2464.8151388645742;2892.5950366948646;8405.285550728195;9530.618775248982;9818.165455936254;10121.949390253001;10333.844992325121;1792.5518620344915;1811.6648420042202;1834.596388794463;1839.7056470964715;1849.7502837117086;122406;124767.75232247743;125614;129258.7074129256;129850.70149298481;11385.203035787416;11530.423478826026;11585.826212606818;11771.941140294244;12232.266064036008;14732.55802325932;14761.48334808298;14765.187914664582;14771.335289911765;14772.778408323884;7472.252774722484;8289.295409890106;8350.66597336112;8480.53357065355;8495.447795893248;6834.350736680008;8465.534395608302;8532.786680332918;8545.658173672984;8578.056413794478;4457.487388950312;4546.795394207203;4551.8634440966825;4557.156925968707;4700.224462963641;1625.8455446732778;1875.681134207188;1923.144200830617;1926.5280006398523;1936.390037138297;831.2975535672347;890.6749036601594;924.7641851327868;924.8174308631624;934.0099494535814;19431.611367772566;20393.18427262893;20819.791802081785;20981.790182097982;21078.89460552718;899.9055099214619;931.4411353188033;958.9232861371073;980.7793246519618;981.4994352880083;10425.687229383131;11090.279131856403;11112.888871111347;11124.499397526968;11178.832317515244;589326;594720;598906.0054699698;623634;624721;84685.15314846773;107719;121416.78583214231;134282;144981.27509362384;870.9782255443664;931.4318265857821;946.753844000551;946.976325591852;948.3977674176947;18115;19182.328618498286;19572.510687232363;19603.80396196077;19684.803151968295;52289.21566176586;53022;54277.72861135669;54987.45012549823;56381.71809140928;60632.393676062675;78147;79841.4031719374;82191;82957.35715321105;7772.84454310922;7809.789482630202;8218.383621228551;8237.83524329522;8252.546109964009;9023.548822558978;12015.579454719043;12729.108962372546;12736;12949.028822838136;11109.777804443704;11157.88842111601;11326.660200194008;11446.484908179318;11549.769004620028;11036.227464077438;11088;11131.276467029616;11403.714907127389;11511.827322590336;11354.829677554679;11365.659030229106;11694.88305116972;11749.353785541878;11770.58802941893;7144.285571442814;7305.727065121696;7458.701651934079;7865;8215.958920205361;8187.672493100209;9649.565769540248;10859;11291.717707057061;11658.06735461178 08:42:40 INFO - PROCESS | 1665 | |3;mootools.html;1447.6091455307064;1485.8737007354375;1618.3041292244338;1761.7005109131446;1808.6563552924952;8161.428699991003;8574.35692323075;8574.614142363593;8598.0112287087;8605.182507661777;7513.849723005536;7734.38124950004;7746.070471543423;8133.2680058794695;8403.95798021009;2306.5041016181517;2345.7067866516686;2348.8708121053337;2356.39911822485;2356.728976167737;2344.109238489372;2363.917262895799;2371.9407014824683;2394.95210095798;2397.220903206456;2060.701198326249;2139.60417322795;2249.1116009176367;2332.0671731307443;2334.6031174700342;2183.683365911942;2184.737831460235;2186.0272178880405;2187.6499760038478;2191.60551100801;2093.6231478333893;2328.4528135888054;2339.9064037438484;2366.065404165345;2366.372911178539;8348.749537513884;8365.665373385058;8444.366672499526;8456.74629761102;8469.110743371919;5080.669756465818;5245.737713114368;5245.86885327866;5246.186841039645;5256.106461901448;3409.573803274591;3501.6148223695373;3544.6987006104514;3555.306715190539;3562.447820587813;1025.2310766924807;1058.2856571814073;1084.9132069434427;1088.37418484371;1100.2188446203206;9261.907380926172;11141.108711303077;11142.387168705718;11143.275687080395;11143.554257829757;4619.9769001155455;4634.165850146938;4809.855704328876;4824.927626085611;4830.106430310404;8757.47455152693;8848.557572121368;8859.468431894107;8860.64557417709;8865.157810008039;5212.661177023481;5215.973920130451;5222.817201398009;5226.007058658843;5227.843164705065;4615.30770384445;4801.51984801517;4849.151398505247;4851.708897466164;4903.019396120719;3506.1234691327168;3540.2034542227793;3577.749557531;3607.981960090183;3633.16437219441;1123.5449642894641;1126.55238019145;1130.2879186112696;1142.731458107344;1144.3991904250336;830.7009476588423;842.0526907229367;863.6977058029639;923.8660394242931;936.2790651198675;5586.832395028156;5596.412376700469;5669.461401166883;5860.941390586125;5950.988331983486;435.8975640724427;455.2124824054404;455.2556570008061;465.29507795689557;465.78108289104745;3897.5968651285516;3924.843006279774;3940.1776008497745;3982.542007669097;4020.075382662004;9872.753181170385;10214.029667181605;10343.896561034293;10344.120749736207;10376.740581485523;10306.948465257776;10308.32995855267;10310;10320.96790320962;10329;7782.377409807202;7945.920540794518;7949.960250198827;7958.482698624571;7994.5203287802915;4619.376384188092;5487.670739755627;5667.1782591524825;5824.5631577631;5846.912296315644;9374.718758437259;9542.809143816947;9585.329026968324;9598.85601715961;9605.279604029865;9129.041450647586;9555.856662149934;9567.425954442755;9574.138327550554;9580.712578622653;7194.676239569337;7354.485186036926;7361.558306501627;7367.5211111277595;7378.372838308804;7856.175101614248;7935.801604959922;7947.8807817883935;7962.800929976797;7985.960070199611;6160.815175544741;7092.219855815753;7279.963600181965;7297.781066568012;7317.195108538163;6372.884745169669;6728.259891411843;7106.218315985134;7144.464165187528;7187.173475050227;6670.633115178712;6745.460363171032;6840.3501667341525;6847.589144651337;6855.862882742417;4922.409310882717;4942.357493525818;4958.8760280993265;4981.252812078073;4988;4882.096812089775;4894.437139728977;4917.975410122926;5058.089543882136;5063.924041139459 08:42:40 INFO - PROCESS | 1665 | |4;prototype.html;286.74336468860355;303.91034644779785;306.2176139962395;312.31914426550117;316.7181208724234;5065.898682026357;5136.2809206711045;5182.922256166161;5279.894402111955;5348.759305831237;4836.516348365161;5169.043726910515;5313.601479889005;5336.546393556552;5336.626436149471;3183.5514840747405;4063.5936406359274;4068.532118806331;4104.774237416941;4134.503859536845;3320.3027364253417;3851.826667799956;4108.897277568055;4130.545639979615;4134.751914885116;3552.2717842842158;4024.798760062;4030.858919937803;4054.5337286212166;4064.1465292288653;3786.4131059685656;3936.114374265796;3984.641382275609;3999.3601023836336;4100.856470023565;2926.736593706555;2996.651506821917;3354.1614596350914;3518.733256027828;3524.9118772030647;4800.543948324904;4922.80308787648;4968.7018778873025;5157.613179011589;5199.6360254782185;4137.731047481904;4257.808398622069;4358.254299791143;4506.752128632924;4669.626429885601;4359.32430473274;5549.445055494459;5554.305711786027;5599.188117722907;5611.915821262684;3563.6436356364416;3597.5323207983056;3819.4652748615217;3840.52432548544;3864.7101467390057;4946.629002824802;5225.372955245395;5822.33043200029;5833.416658334175;5868.4424979626865;3889.630485103911;3892.3966785148345;3918.1575961168487;3945.349017412152;3947.585503522089;3810.447485114643;3883.262180185756;3965.0373065332847;3984.601539845992;3985.461962635065;4349.3475978603465;4409.470863496401;4442.955905362237;4449.065696203768;4551.749653769009;5163.845084647466;5336.839894803162;5565.666060036411;5638.577106717013;5644.407337229532;1227.2943057741834;1228.8218208359738;1239.3617287097152;1281.8654041325713;1294.70869054462;805.806606414468;819.6598411659214;860.5267103093273;878.8198419324024;889.7019498467956;4133.762893034604;4667.579917807414;4847.975760121177;4884.9511504884495;4970.577500912391;5280.788768449219;5322.866928326823;5361.12360673761;5410.97294513525;5440.238366628675;4287.656987440997;4529.954700452953;4531.614812740954;4629.20405267684;4909.514244886171;2426.526827268666;2464.53173896959;2821.6190814239817;2837.1063115118673;2871.6984716604875;5046.949530504648;5341;5627.352854421794;5630.465105814941;5640.32316122068;4792.95207047932;5123.976029468339;5304.893902121936;5506.504414602705;5998;5346.198070289488;5840.8539786504825;6049.909251361321;6067.757289708362;6121.622634907199;5492.489565369556;5833.724847309468;5837.3870743571315;5849.981756294072;5864.633860018206;5255.448177941261;5352.875896061871;5480.506754392124;5715.39988301239;5747.798827041033;5278.709670968134;5615.522680572197;5992.730327135377;5996.790112346047;6006.579539432198;3541.972827879989;3912.3544615138185;3996.4404983302943;4025.879223623296;4065.9428548576993;4986.850394488171;5494.928488944617;5727.852055479195;5787.768489260382;5820.039693450533;4826.927596086131;5183.611229157753;5254.527092561688;5286.603504737083;5453.236546883519;5084.339035925306;5370.764724113397;5656.3495198051105;5660.405657405913;5665.830025099089;5126.512981266774;5272.367315922113;5505.366882808368;5559.498225798326;5605.085355615139;5574.470425309589;5635.718214089361;5637.43625637433;5649.774009039593;5677.801276955287;5522.9447705524035;5527.8894422112135;5531.917021244765;5540.168974653834;5549.916751248654 08:42:40 INFO - PROCESS | 1665 | |5;yui.html;632.1529150937745;689.2934741889562;689.551791335632;735.793977686248;774.0208636075362;14003.719925601494;14464.855351446482;14467.638309042253;14469.855301446982;14480.203588802611;12756.617301480946;14236.430542778277;14769.409223631043;14806.851931480682;15114.093154410742;516.9663971841837;651.853333000076;654.6366766444621;813.4184058398245;813.556611646654;668.2548957911941;783.1893989720612;808.1474044882647;822.3174764945081;825.455199568285;860.6428332242112;864.2912811494577;865.7056600755742;866.0257210638032;881.0132651430407;664.8271449423137;861.7371701631;862.348926560446;862.4307956748547;862.9050804411572;509.7629602234978;608.8508315462695;637.4517914593446;641.2593454559991;642.4539141729539;14229.359678814479;14278.64303392413;14281.928590357085;14284.500042498566;14304.284785760774;12586.81119783204;12749;12775.552855650101;12943.417546210443;13100.672483187902;12124.27254364741;12250.114375509727;12266.632001039983;12293.970710195823;12474;3659.890203293879;3669.6243832563837;3789;4338.674599405026;4348.652107831397;61283.69358153225;62141.6892915537;77878;80036.19963800345;81849.59075204645;8705.39062265648;10382.844257336146;10463.162946964225;10502.317349372266;10517.52671129801;19655.508612284808;19910;19947;20125.698114528295;20173.798262017193;7331.303526165006;7497.737579184703;7641.197674244236;7652.885206721904;7753.6123193840085;4088.468499095158;4167.604077612622;4175.665946724254;4257.467816522934;4370.781460926941;3400.285939952638;3435.124043368925;3492.511048453219;3501.4047611906035;3541;648.4423395879539;653.5523166630873;656.4813797100237;664.9601023938579;667.3693359775064;609.774383478116;633.7560039384778;662.1326062857672;666.1706175811146;683.7982795075465;8754.774331593582;8806.427582207136;9170.349337119249;9232.676856309998;9276.628934842665;565.6481010385182;593.3621357041199;652.7911068458047;670.0251134599147;670.4334837062675;3806.4100064489494;3988.6410223079483;4344.196323680129;4433.467983841895;4456.487503937024;76422.61788691132;91733;101984.49007754914;111186;123392;106057.46971265094;111179.88820111695;111199;111482.44258778654;112435.87564124417;85946.57026714826;92864;94138.52930735302;100931.49534252282;100944;8595.52724600153;8620;8704.91295087041;8770.298376130022;8839.46963182211;59518.70240648769;59565.51086222879;60171;60743;63405.365946339945;72928;77563.2243677556;79521.07183922458;80058.59970700109;91336.08663913276;10634.73413164677;10742.677719668422;10955.65852415021;11177.608783692856;11198.608048718255;11829.526818927146;11968.341741204318;12027.699307517381;12400.13199076056;12703.42834572465;11167.593883865498;11746.412679365827;12175;12473.126881118234;12804.487820487077;12032.518699251932;12639.810402844148;12969.221846689228;13099.803502947654;13421.865781342452;11838.940805295919;13470.393832277767;13501.189928604315;13632;13675.931620341835;9938.602455901972;10736.248462607855;10825.40460274661;10837.512311946139;11138.443077846237;9081;9913.65302214419;10933.83599245996;10984.725381865517;11032.28290161137 08:42:40 INFO - PROCESS | 1665 | __end_tp_report 08:42:40 INFO - PROCESS | 1665 | __start_cc_report 08:42:40 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1671 08:42:40 INFO - PROCESS | 1665 | __end_cc_report 08:42:40 INFO - PROCESS | 1665 | __startTimestamp1485103360707__endTimestamp 08:42:40 INFO - PROCESS | 1665 | 08:42:40 INFO - PROCESS | 1665 | ------- Summary: start ------- 08:42:40 INFO - PROCESS | 1665 | Number of tests: 6 08:42:40 INFO - PROCESS | 1665 | 08:42:40 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5696.98 Median:5898.82 stddev:644.39 (10.9%) stddev-sans-first:617.59 08:42:40 INFO - PROCESS | 1665 | Values: 3269.4 3273.7 3289.5 3311.0 3331.8 5538.6 5581.1 5581.2 5582.9 5592.3 5575.3 5654.3 5666.0 5675.5 5717.9 5115.8 5129.9 5143.6 5181.5 5202.8 5098.8 5111.8 5120.1 5121.0 5308.0 5177.1 5221.8 5256.2 5259.4 5269.6 5224.5 5230.1 5234.5 5247.6 5269.3 4990.7 5119.0 5157.8 5162.4 5164.4 5539.3 5557.2 5585.2 5635.4 5651.5 6106.0 6124.7 6128.6 6197.3 6198.8 6249.7 6277.3 6281.0 6284.8 6289.7 5542.5 5607.6 5667.1 5770.0 5790.3 6322.9 6324.9 6336.9 6357.9 6362.4 6064.5 6089.6 6091.1 6094.6 6107.6 6073.9 6129.3 6129.3 6131.0 6144.5 6092.4 6140.0 6141.6 6193.1 6195.1 6102.6 6201.6 6206.4 6208.6 6215.0 4891.3 4904.6 4910.5 4917.1 4938.8 4330.5 4355.4 4358.6 4383.6 4448.3 5726.7 5730.6 5738.7 5742.9 5781.6 6223.4 6257.6 6260.4 6262.2 6270.2 4887.2 5576.0 5620.7 5626.3 5669.2 5345.7 5346.8 5349.3 5370.4 5391.9 6267.0 6283.6 6327.9 6336.8 6349.9 5901.9 6136.3 6146.0 6268.3 6274.0 6247.5 6301.7 6308.0 6310.7 6320.8 6018.7 6139.7 6145.0 6148.5 6158.3 6066.7 6116.9 6230.5 6235.8 6249.8 5982.0 5993.0 6033.6 6084.9 6108.1 6052.3 6053.5 6060.3 6060.9 6081.7 5691.7 5815.1 5831.6 5859.4 5887.8 5908.9 5947.9 5974.4 6012.0 6043.5 5891.4 5898.0 5898.8 5908.8 5916.0 08:42:40 INFO - PROCESS | 1665 | 08:42:40 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:65139.94 Median:36168.64 stddev:139660.52 (386.1%) stddev-sans-first:139967.97 08:42:40 INFO - PROCESS | 1665 | Values: 498.5 502.8 506.8 515.4 522.1 30229.9 32071.0 32076.5 39084.4 40186.0 42103.8 42451.4 42453.0 42488.0 42505.1 9378.9 9522.8 9527.1 9531.7 9535.1 8482.6 8687.3 9370.9 11197.7 11480.1 11436.0 11480.9 11527.7 11531.4 11574.0 11351.4 11396.7 11410.0 11417.2 11424.4 8434.9 8663.9 11116.2 11346.0 11475.7 39894.8 40076.6 40152.0 40230.4 40246.0 2970.1 3181.1 3247.7 3307.1 3486.5 29569.7 29938.4 30399.0 30795.9 31035.8 3174.3 3183.8 3189.8 3202.7 3221.5 469404.0 470352.0 470643.6 471628.0 473467.0 61055.7 61440.0 61541.1 61543.7 61544.0 64525.7 64749.0 64754.4 64771.0 64804.0 15731.5 16240.4 16252.8 16256.8 16261.3 12467.3 12519.9 12529.6 12549.3 12571.0 15448.8 15809.3 15836.1 16076.4 16114.9 5993.4 6330.0 7016.6 7036.5 7041.6 831.3 931.0 968.6 988.6 1019.6 28602.9 29011.6 29081.4 29516.0 30016.4 890.8 934.1 1058.9 1090.4 1109.8 14722.9 15128.4 15257.4 15475.6 15593.6 637544.0 642880.0 642956.0 643767.0 645398.0 49263.3 50089.5 50139.7 54379.7 55680.0 1269.9 1322.2 1344.6 1358.1 1365.9 23689.3 27923.2 29949.7 30064.5 30763.7 51026.7 51608.7 51642.5 51647.5 51852.0 419986.0 427491.0 435664.0 435949.8 436549.0 21507.8 23283.5 25375.7 25386.4 25440.0 44565.6 44675.0 44851.8 45105.5 45187.0 33134.2 33867.7 37023.0 37212.1 37364.1 36278.3 36740.3 37189.0 37250.8 38306.4 29835.9 36059.0 36550.5 37061.4 37376.6 31905.0 33288.2 33791.0 34048.1 34144.0 25179.5 25438.4 25445.6 25584.0 25637.7 08:42:40 INFO - PROCESS | 1665 | 08:42:40 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:34901.14 Median:382.65 stddev:101226.93 (26454.5%) stddev-sans-first:101477.57 08:42:40 INFO - PROCESS | 1665 | Values: 306.6 376.6 376.7 388.6 405.7 14710.2 14826.0 15747.4 20097.0 20702.6 19283.3 20151.0 20186.5 20288.4 20532.0 5682.6 5934.0 5934.8 5943.7 5962.9 4583.3 5775.6 6554.9 6587.0 6591.9 6386.0 6429.9 6516.0 6535.1 6543.7 6427.8 6441.3 6447.9 6453.6 6461.7 5116.8 5400.4 5439.7 5492.8 5653.9 13146.3 13428.5 13439.8 22986.8 24692.5 2109.8 2352.5 2430.7 2464.8 2892.6 8405.3 9530.6 9818.2 10121.9 10333.8 1792.6 1811.7 1834.6 1839.7 1849.8 122406.0 124767.8 125614.0 129258.7 129850.7 11385.2 11530.4 11585.8 11771.9 12232.3 14732.6 14761.5 14765.2 14771.3 14772.8 7472.3 8289.3 8350.7 8480.5 8495.4 6834.4 8465.5 8532.8 8545.7 8578.1 4457.5 4546.8 4551.9 4557.2 4700.2 1625.8 1875.7 1923.1 1926.5 1936.4 831.3 890.7 924.8 924.8 934.0 19431.6 20393.2 20819.8 20981.8 21078.9 899.9 931.4 958.9 980.8 981.5 10425.7 11090.3 11112.9 11124.5 11178.8 589326.0 594720.0 598906.0 623634.0 624721.0 84685.2 107719.0 121416.8 134282.0 144981.3 871.0 931.4 946.8 947.0 948.4 18115.0 19182.3 19572.5 19603.8 19684.8 52289.2 53022.0 54277.7 54987.5 56381.7 60632.4 78147.0 79841.4 82191.0 82957.4 7772.8 7809.8 8218.4 8237.8 8252.5 9023.5 12015.6 12729.1 12736.0 12949.0 11109.8 11157.9 11326.7 11446.5 11549.8 11036.2 11088.0 11131.3 11403.7 11511.8 11354.8 11365.7 11694.9 11749.4 11770.6 7144.3 7305.7 7458.7 7865.0 8216.0 8187.7 9649.6 10859.0 11291.7 11658.1 08:42:40 INFO - PROCESS | 1665 | 08:42:40 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5463.40 Median:4984.63 stddev:3020.21 (60.6%) stddev-sans-first:3013.61 08:42:40 INFO - PROCESS | 1665 | Values: 1447.6 1485.9 1618.3 1761.7 1808.7 8161.4 8574.4 8574.6 8598.0 8605.2 7513.8 7734.4 7746.1 8133.3 8404.0 2306.5 2345.7 2348.9 2356.4 2356.7 2344.1 2363.9 2371.9 2395.0 2397.2 2060.7 2139.6 2249.1 2332.1 2334.6 2183.7 2184.7 2186.0 2187.6 2191.6 2093.6 2328.5 2339.9 2366.1 2366.4 8348.7 8365.7 8444.4 8456.7 8469.1 5080.7 5245.7 5245.9 5246.2 5256.1 3409.6 3501.6 3544.7 3555.3 3562.4 1025.2 1058.3 1084.9 1088.4 1100.2 9261.9 11141.1 11142.4 11143.3 11143.6 4620.0 4634.2 4809.9 4824.9 4830.1 8757.5 8848.6 8859.5 8860.6 8865.2 5212.7 5216.0 5222.8 5226.0 5227.8 4615.3 4801.5 4849.2 4851.7 4903.0 3506.1 3540.2 3577.7 3608.0 3633.2 1123.5 1126.6 1130.3 1142.7 1144.4 830.7 842.1 863.7 923.9 936.3 5586.8 5596.4 5669.5 5860.9 5951.0 435.9 455.2 455.3 465.3 465.8 3897.6 3924.8 3940.2 3982.5 4020.1 9872.8 10214.0 10343.9 10344.1 10376.7 10306.9 10308.3 10310.0 10321.0 10329.0 7782.4 7945.9 7950.0 7958.5 7994.5 4619.4 5487.7 5667.2 5824.6 5846.9 9374.7 9542.8 9585.3 9598.9 9605.3 9129.0 9555.9 9567.4 9574.1 9580.7 7194.7 7354.5 7361.6 7367.5 7378.4 7856.2 7935.8 7947.9 7962.8 7986.0 6160.8 7092.2 7280.0 7297.8 7317.2 6372.9 6728.3 7106.2 7144.5 7187.2 6670.6 6745.5 6840.4 6847.6 6855.9 4922.4 4942.4 4958.9 4981.3 4988.0 4882.1 4894.4 4918.0 5058.1 5063.9 08:42:40 INFO - PROCESS | 1665 | 08:42:40 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4482.97 Median:5075.12 stddev:1391.47 (27.4%) stddev-sans-first:1359.26 08:42:40 INFO - PROCESS | 1665 | Values: 286.7 303.9 306.2 312.3 316.7 5065.9 5136.3 5182.9 5279.9 5348.8 4836.5 5169.0 5313.6 5336.5 5336.6 3183.6 4063.6 4068.5 4104.8 4134.5 3320.3 3851.8 4108.9 4130.5 4134.8 3552.3 4024.8 4030.9 4054.5 4064.1 3786.4 3936.1 3984.6 3999.4 4100.9 2926.7 2996.7 3354.2 3518.7 3524.9 4800.5 4922.8 4968.7 5157.6 5199.6 4137.7 4257.8 4358.3 4506.8 4669.6 4359.3 5549.4 5554.3 5599.2 5611.9 3563.6 3597.5 3819.5 3840.5 3864.7 4946.6 5225.4 5822.3 5833.4 5868.4 3889.6 3892.4 3918.2 3945.3 3947.6 3810.4 3883.3 3965.0 3984.6 3985.5 4349.3 4409.5 4443.0 4449.1 4551.7 5163.8 5336.8 5565.7 5638.6 5644.4 1227.3 1228.8 1239.4 1281.9 1294.7 805.8 819.7 860.5 878.8 889.7 4133.8 4667.6 4848.0 4885.0 4970.6 5280.8 5322.9 5361.1 5411.0 5440.2 4287.7 4530.0 4531.6 4629.2 4909.5 2426.5 2464.5 2821.6 2837.1 2871.7 5046.9 5341.0 5627.4 5630.5 5640.3 4793.0 5124.0 5304.9 5506.5 5998.0 5346.2 5840.9 6049.9 6067.8 6121.6 5492.5 5833.7 5837.4 5850.0 5864.6 5255.4 5352.9 5480.5 5715.4 5747.8 5278.7 5615.5 5992.7 5996.8 6006.6 3542.0 3912.4 3996.4 4025.9 4065.9 4986.9 5494.9 5727.9 5787.8 5820.0 4826.9 5183.6 5254.5 5286.6 5453.2 5084.3 5370.8 5656.3 5660.4 5665.8 5126.5 5272.4 5505.4 5559.5 5605.1 5574.5 5635.7 5637.4 5649.8 5677.8 5522.9 5527.9 5531.9 5540.2 5549.9 08:42:40 INFO - PROCESS | 1665 | 08:42:40 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:20780.47 Median:4341.44 stddev:30989.31 (713.8%) stddev-sans-first:31039.31 08:42:40 INFO - PROCESS | 1665 | Values: 632.2 689.3 689.6 735.8 774.0 14003.7 14464.9 14467.6 14469.9 14480.2 12756.6 14236.4 14769.4 14806.9 15114.1 517.0 651.9 654.6 813.4 813.6 668.3 783.2 808.1 822.3 825.5 860.6 864.3 865.7 866.0 881.0 664.8 861.7 862.3 862.4 862.9 509.8 608.9 637.5 641.3 642.5 14229.4 14278.6 14281.9 14284.5 14304.3 12586.8 12749.0 12775.6 12943.4 13100.7 12124.3 12250.1 12266.6 12294.0 12474.0 3659.9 3669.6 3789.0 4338.7 4348.7 61283.7 62141.7 77878.0 80036.2 81849.6 8705.4 10382.8 10463.2 10502.3 10517.5 19655.5 19910.0 19947.0 20125.7 20173.8 7331.3 7497.7 7641.2 7652.9 7753.6 4088.5 4167.6 4175.7 4257.5 4370.8 3400.3 3435.1 3492.5 3501.4 3541.0 648.4 653.6 656.5 665.0 667.4 609.8 633.8 662.1 666.2 683.8 8754.8 8806.4 9170.3 9232.7 9276.6 565.6 593.4 652.8 670.0 670.4 3806.4 3988.6 4344.2 4433.5 4456.5 76422.6 91733.0 101984.5 111186.0 123392.0 106057.5 111179.9 111199.0 111482.4 112435.9 85946.6 92864.0 94138.5 100931.5 100944.0 8595.5 8620.0 8704.9 8770.3 8839.5 59518.7 59565.5 60171.0 60743.0 63405.4 72928.0 77563.2 79521.1 80058.6 91336.1 10634.7 10742.7 10955.7 11177.6 11198.6 11829.5 11968.3 12027.7 12400.1 12703.4 11167.6 11746.4 12175.0 12473.1 12804.5 12032.5 12639.8 12969.2 13099.8 13421.9 11838.9 13470.4 13501.2 13632.0 13675.9 9938.6 10736.2 10825.4 10837.5 11138.4 9081.0 9913.7 10933.8 10984.7 11032.3 08:42:40 INFO - PROCESS | 1665 | -------- Summary: end -------- 08:42:40 INFO - PROCESS | 1665 | 08:42:41 INFO - TEST-INFO | 1665: exit 0 08:42:41 INFO - TEST-OK | dromaeo_css | took 1118091ms 08:42:41 INFO - TEST-START | kraken 08:42:41 INFO - operating with platform_type : mac_ 08:42:41 INFO - Initialising browser for kraken test... 08:42:41 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpG7lMYs/profile http://localhost:49247/getInfo.html) 08:42:43 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 08:42:43 INFO - PROCESS | 1671 | colorDepth:24 08:42:43 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 08:42:43 INFO - PROCESS | 1671 | __metrics 08:42:43 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:42:43 INFO - PROCESS | 1671 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:42:44 INFO - TEST-INFO | 1671: exit 0 08:42:44 INFO - Browser initialized. 08:42:44 INFO - Running cycle 1/1 for kraken test... 08:42:44 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpG7lMYs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:42:56 INFO - PROCESS | 1672 | RSS: Main: 222294016 08:42:56 INFO - PROCESS | 1672 | 08:42:59 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 08:43:00 INFO - PROCESS | 1672 | RSS: Main: 271949824 08:43:00 INFO - PROCESS | 1672 | 08:43:03 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 08:43:03 INFO - PROCESS | 1672 | RSS: Main: 283197440 08:43:03 INFO - PROCESS | 1672 | 08:43:07 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 08:43:07 INFO - PROCESS | 1672 | RSS: Main: 381599744 08:43:07 INFO - PROCESS | 1672 | 08:43:09 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 08:43:10 INFO - PROCESS | 1672 | RSS: Main: 283463680 08:43:10 INFO - PROCESS | 1672 | 08:43:12 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:43:12 INFO - PROCESS | 1672 | RSS: Main: 284241920 08:43:12 INFO - PROCESS | 1672 | 08:43:15 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 08:43:16 INFO - PROCESS | 1672 | RSS: Main: 323436544 08:43:16 INFO - PROCESS | 1672 | 08:43:19 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 08:43:20 INFO - PROCESS | 1672 | RSS: Main: 327016448 08:43:20 INFO - PROCESS | 1672 | 08:43:22 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 08:43:23 INFO - PROCESS | 1672 | RSS: Main: 322244608 08:43:23 INFO - PROCESS | 1672 | 08:43:25 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:43:25 INFO - PROCESS | 1672 | RSS: Main: 297668608 08:43:25 INFO - PROCESS | 1672 | 08:43:27 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:43:28 INFO - PROCESS | 1672 | RSS: Main: 300380160 08:43:28 INFO - PROCESS | 1672 | 08:43:30 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:43:30 INFO - PROCESS | 1672 | RSS: Main: 302907392 08:43:30 INFO - PROCESS | 1672 | 08:43:33 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:43:34 INFO - PROCESS | 1672 | RSS: Main: 322019328 08:43:34 INFO - PROCESS | 1672 | 08:43:37 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:43:38 INFO - PROCESS | 1672 | RSS: Main: 315965440 08:43:38 INFO - PROCESS | 1672 | 08:43:39 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 08:43:40 INFO - PROCESS | 1672 | RSS: Main: 360087552 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | __start_tp_report 08:43:40 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 08:43:40 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 08:43:40 INFO - PROCESS | 1672 | |i|pagename|runs| 08:43:40 INFO - PROCESS | 1672 | |0;ai-astar;114;114;113;111;113;113;109;105;113;112 08:43:40 INFO - PROCESS | 1672 | |1;audio-beat-detection;151;137;157;161;109;112;120;106;145;111 08:43:40 INFO - PROCESS | 1672 | |2;audio-dft;182;181;198;256;195;183;182;184;184;184 08:43:40 INFO - PROCESS | 1672 | |3;audio-fft;81;60;60;88;64;58;60;60;82;58 08:43:40 INFO - PROCESS | 1672 | |4;audio-oscillator;87;70;92;69;67;70;89;89;99;123 08:43:40 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;88;86;89;85;88;84;88;84;88;85 08:43:40 INFO - PROCESS | 1672 | |6;imaging-darkroom;93;108;127;137;87;132;98;122;137;147 08:43:40 INFO - PROCESS | 1672 | |7;imaging-desaturate;85;83;79;80;84;81;83;83;80;89 08:43:40 INFO - PROCESS | 1672 | |8;json-parse-financial;59;55;55;56;54;58;58;56;55;57 08:43:40 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;64;71;72;76;74;70;71;71;71;72 08:43:40 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;83;86;84;83;85;84;84;84;82;85 08:43:40 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;140;141;142;137;137;153;165;161;196;155 08:43:40 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;167;173;165;163;165;165;165;165;165;166 08:43:40 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;67;67;67;67;66;65;66;66;65;66 08:43:40 INFO - PROCESS | 1672 | __end_tp_report 08:43:40 INFO - PROCESS | 1672 | __start_cc_report 08:43:40 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1955 08:43:40 INFO - PROCESS | 1672 | __end_cc_report 08:43:40 INFO - PROCESS | 1672 | __startTimestamp1485103420047__endTimestamp 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | ------- Summary: start ------- 08:43:40 INFO - PROCESS | 1672 | Number of tests: 14 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:2.79 (2.5%) stddev-sans-first:2.83 08:43:40 INFO - PROCESS | 1672 | Values: 114.0 114.0 113.0 111.0 113.0 113.0 109.0 105.0 113.0 112.0 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:141.00 stddev:21.60 (15.3%) stddev-sans-first:21.65 08:43:40 INFO - PROCESS | 1672 | Values: 151.0 137.0 157.0 161.0 109.0 112.0 120.0 106.0 145.0 111.0 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:192.90 Median:184.00 stddev:22.92 (12.5%) stddev-sans-first:23.97 08:43:40 INFO - PROCESS | 1672 | Values: 182.0 181.0 198.0 256.0 195.0 183.0 182.0 184.0 184.0 184.0 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:67.10 Median:62.00 stddev:11.69 (18.8%) stddev-sans-first:11.26 08:43:40 INFO - PROCESS | 1672 | Values: 81.0 60.0 60.0 88.0 64.0 58.0 60.0 60.0 82.0 58.0 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:85.50 Median:88.00 stddev:17.49 (19.9%) stddev-sans-first:18.54 08:43:40 INFO - PROCESS | 1672 | Values: 87.0 70.0 92.0 69.0 67.0 70.0 89.0 89.0 99.0 123.0 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:88.00 stddev:1.90 (2.2%) stddev-sans-first:1.94 08:43:40 INFO - PROCESS | 1672 | Values: 88.0 86.0 89.0 85.0 88.0 84.0 88.0 84.0 88.0 85.0 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:118.80 Median:142.00 stddev:20.91 (14.7%) stddev-sans-first:19.99 08:43:40 INFO - PROCESS | 1672 | Values: 93.0 108.0 127.0 137.0 87.0 132.0 98.0 122.0 137.0 147.0 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:82.70 Median:83.00 stddev:2.95 (3.5%) stddev-sans-first:3.00 08:43:40 INFO - PROCESS | 1672 | Values: 85.0 83.0 79.0 80.0 84.0 81.0 83.0 83.0 80.0 89.0 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:56.50 stddev:1.64 (2.9%) stddev-sans-first:1.41 08:43:40 INFO - PROCESS | 1672 | Values: 59.0 55.0 55.0 56.0 54.0 58.0 58.0 56.0 55.0 57.0 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:71.50 stddev:3.08 (4.3%) stddev-sans-first:1.87 08:43:40 INFO - PROCESS | 1672 | Values: 64.0 71.0 72.0 76.0 74.0 70.0 71.0 71.0 71.0 72.0 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:1.15 (1.4%) stddev-sans-first:1.17 08:43:40 INFO - PROCESS | 1672 | Values: 83.0 86.0 84.0 83.0 85.0 84.0 84.0 84.0 82.0 85.0 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:152.70 Median:154.00 stddev:18.28 (11.9%) stddev-sans-first:18.80 08:43:40 INFO - PROCESS | 1672 | Values: 140.0 141.0 142.0 137.0 137.0 153.0 165.0 161.0 196.0 155.0 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.90 Median:165.00 stddev:2.69 (1.6%) stddev-sans-first:2.82 08:43:40 INFO - PROCESS | 1672 | Values: 167.0 173.0 165.0 163.0 165.0 165.0 165.0 165.0 165.0 166.0 08:43:40 INFO - PROCESS | 1672 | 08:43:40 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:0.79 (1.2%) stddev-sans-first:0.78 08:43:40 INFO - PROCESS | 1672 | Values: 67.0 67.0 67.0 67.0 66.0 65.0 66.0 66.0 65.0 66.0 08:43:40 INFO - PROCESS | 1672 | -------- Summary: end -------- 08:43:40 INFO - PROCESS | 1672 | 08:43:41 INFO - TEST-INFO | 1672: exit 0 08:43:41 INFO - TEST-OK | kraken | took 59964ms 08:43:41 INFO - SUITE-END | took 1178s 08:43:41 INFO - Completed test suite (00:19:38) 08:43:41 INFO - javascript benchmark 08:43:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3269.4115059289325, 3273.721733652641, 3289.4901290299995, 3311.0066979906023, 3331.8167500787463, 5538.58460615454, 5581.107022876341, 5581.218629391883, 5582.916256256154, 5592.273004509408, 5575.275214222155, 5654.293213348332, 5666.036773748462, 5675.489205971462, 5717.914231286523, 5115.7697903594335, 5129.871753206181, 5143.588512918957, 5181.5077567631015, 5202.791888324463, 5098.821541246057, 5111.821086261982, 5120.078385890538, 5121.027004869082, 5308.0, 5177.119889618773, 5221.791128354861, 5256.159014557672, 5259.368875734898, 5269.552088072518, 5224.52979231871, 5230.084735171368, 5234.476552344754, 5247.553957913563, 5269.2886460327745, 4990.65065445419, 5119.0, 5157.767900444489, 5162.432132465426, 5164.406093299281, 5539.307586551681, 5557.166425036236, 5585.162225666142, 5635.351934527541, 5651.519620832193, 6105.961986462312, 6124.7243874025335, 6128.601640893372, 6197.349278325801, 6198.81403557894, 6249.6875156242, 6277.309495955441, 6281.0, 6284.780032698834, 6289.748410063592, 5542.528885044736, 5607.607467477278, 5667.064934285797, 5770.0, 5790.334111577223, 6322.893493638595, 6324.891169386798, 6336.904946425715, 6357.904631430532, 6362.395572420705, 6064.48451881595, 6089.604175728564, 6091.0558863376245, 6094.634321940698, 6107.603005804608, 6073.908891366633, 6129.297663866018, 6129.325774164839, 6130.969345153246, 6144.539159563051, 6092.360302168208, 6140.04415716754, 6141.560725993369, 6193.102000209944, 6195.132681424604, 6102.6338419694075, 6201.565890387629, 6206.379362063757, 6208.565400422018, 6215.0, 4891.266310053449, 4904.60763138948, 4910.533499317559, 4917.0903382874285, 4938.8271410501175, 4330.501992270866, 4355.412019377343, 4358.629516491071, 4383.583559561836, 4448.310511870665, 5726.713664316768, 5730.627509211888, 5738.741756620962, 5742.885142297131, 5781.566382521328, 6223.439890409885, 6257.624542527463, 6260.436560709558, 6262.1546091278005, 6270.184875966186, 4887.169181239198, 5575.996320662239, 5620.690862002629, 5626.324841019022, 5669.177969194444, 5345.679259244458, 5346.786128554814, 5349.277847490539, 5370.355557333145, 5391.865203369947, 6267.0, 6283.591566548068, 6327.873442531216, 6336.841578960379, 6349.904751428732, 5901.940980590139, 6136.274005701014, 6146.0473626587955, 6268.341824108586, 6274.0, 6247.468965137832, 6301.684915754286, 6307.968460157854, 6310.6844657766005, 6320.841978950563, 6018.699065046642, 6139.693015349126, 6144.969275153596, 6148.508119350352, 6158.291796443466, 6066.666333351708, 6116.944513560587, 6230.532710046855, 6235.75056997715, 6249.812505624838, 5982.0, 5993.011153159854, 6033.577649564489, 6084.939150608438, 6108.11432342299, 6052.273727152769, 6053.545984051302, 6060.303065147565, 6060.878782424415, 6081.665508397081, 5691.658500489983, 5815.069588865761, 5831.562632802472, 5859.414058594107, 5887.8233652990475, 5908.911366329423, 5947.910781338197, 5974.40255974399, 6012.0, 6043.54673399488, 5891.410858914074, 5898.0, 5898.8230353089475, 5908.822735317947, 5915.970420147872], "value": 5654.229362261183, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.5214194373401, 502.84914525642296, 506.7508591322391, 515.4150039704936, 522.1176212201377, 30229.941952031684, 32070.99999999997, 32076.518852217236, 39084.41373379402, 40186.0, 42103.78948105263, 42451.363229551505, 42453.0, 42488.0, 42505.14989700212, 9378.859317110231, 9522.80954380912, 9527.142557169853, 9531.666391676292, 9535.094166054214, 8482.618282177316, 8687.261582765472, 9370.906290937106, 11197.66407007787, 11480.081593472543, 11435.970762631361, 11480.942595287011, 11527.711807204803, 11531.365774882379, 11573.95834374906, 11351.43242837859, 11396.658100256922, 11410.030147437477, 11417.200795944287, 11424.371659558763, 8434.915650843473, 8663.870041949376, 11116.166287528467, 11345.978861902386, 11475.655730328104, 39894.800525997474, 40076.599234007575, 40152.0, 40230.39654405157, 40246.0, 2970.12381347503, 3181.109289398972, 3247.6914693104354, 3307.090550098742, 3486.477028445728, 29569.70430295691, 29938.401231975455, 30399.0, 30795.922142724896, 31035.844820775976, 3174.2699179188917, 3183.8248896310693, 3189.8388118673724, 3202.727768139711, 3221.4523530999786, 469404.0, 470352.0, 470643.6467817673, 471628.0, 473467.0, 61055.694721527, 61440.0, 61541.07688384678, 61543.69228153831, 61544.0, 64525.677371612845, 64749.0, 64754.35245647483, 64771.0, 64804.0, 15731.528054158394, 16240.350385984664, 16252.83747162537, 16256.837431625532, 16261.268242929096, 12467.251964881954, 12519.874801251872, 12529.624111276677, 12549.309787961749, 12571.0, 15448.768268475982, 15809.288582013836, 15836.12901290417, 16076.356945722275, 16114.919425402797, 5993.430624090704, 6329.968350158219, 7016.649167541603, 7036.472264580177, 7041.556480921399, 831.2850948184556, 930.9906900931039, 968.6077138758814, 988.6292640259902, 1019.5819713917407, 28602.855885764337, 29011.564826527196, 29081.418371632448, 29516.0, 30016.39967200644, 890.7906641939139, 934.0892629686138, 1058.9258751887294, 1090.373035504588, 1109.7724966381872, 14722.926385368004, 15128.39486420553, 15257.389704411698, 15475.613109672347, 15593.610159746097, 637544.0, 642880.0, 642956.0, 643767.0, 645398.0, 49263.261051084264, 50089.49910500849, 50139.74930125399, 54379.72810135924, 55680.0, 1269.9301538415107, 1322.2066759944148, 1344.5697376839319, 1358.1443690475135, 1365.863413658646, 23689.28932132039, 27923.18499297539, 29949.70050299556, 30064.549031764105, 30763.692363076978, 51026.74486627692, 51608.74195628998, 51642.48357516527, 51647.48352516577, 51852.0, 419986.0, 427491.0, 435664.0, 435949.82025089674, 436549.0, 21507.78492215058, 23283.534329313658, 25375.731213439623, 25386.365340866625, 25440.0, 44565.55434445614, 44675.0, 44851.775741121084, 45105.548944510134, 45187.0, 33134.17164570905, 33867.66132338645, 37023.0, 37212.06969825776, 37364.06589835276, 36278.27443451169, 36740.26519469649, 37189.0, 37250.8137459311, 38306.42540361953, 29835.850820746626, 36059.0, 36550.4517432244, 37061.44407833939, 37376.62623373731, 31905.0, 33288.18960366519, 33791.0, 34048.14879628029, 34144.0, 25179.49641007206, 25438.364040899127, 25445.61831572565, 25584.0, 25637.743622563536], "value": 19446.27729197915, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.5876396247047, 376.61585183113226, 376.71369758983184, 388.57839244419813, 405.663299461447, 14710.190939498323, 14826.0, 15747.448839290628, 20097.0, 20702.585948281063, 19283.325083622076, 20150.99245037746, 20186.49533761657, 20288.391348259538, 20532.0, 5682.573806964473, 5934.020886553713, 5934.762609495615, 5943.702814859262, 5962.910556341659, 4583.266677331628, 5775.624584402021, 6554.8689026219445, 6586.9670651646675, 6591.93408065918, 6385.968070159666, 6429.935700643004, 6515.967420162892, 6535.0524173994745, 6543.672816359187, 6427.8393040173905, 6441.323661015573, 6447.903281450758, 6453.612783233021, 6461.709223084972, 5116.820911268088, 5400.405955344909, 5439.700816455093, 5492.780288788444, 5653.886922261573, 13146.342682865818, 13428.462861485528, 13439.798403023962, 22986.77013229863, 24692.506149876903, 2109.778473260301, 2352.494213744036, 2430.708315002211, 2464.8151388645742, 2892.5950366948646, 8405.285550728195, 9530.618775248982, 9818.165455936254, 10121.949390253001, 10333.844992325121, 1792.5518620344915, 1811.6648420042202, 1834.596388794463, 1839.7056470964715, 1849.7502837117086, 122406.0, 124767.75232247743, 125614.0, 129258.7074129256, 129850.70149298481, 11385.203035787416, 11530.423478826026, 11585.826212606818, 11771.941140294244, 12232.266064036008, 14732.55802325932, 14761.48334808298, 14765.187914664582, 14771.335289911765, 14772.778408323884, 7472.252774722484, 8289.295409890106, 8350.66597336112, 8480.53357065355, 8495.447795893248, 6834.350736680008, 8465.534395608302, 8532.786680332918, 8545.658173672984, 8578.056413794478, 4457.487388950312, 4546.795394207203, 4551.8634440966825, 4557.156925968707, 4700.224462963641, 1625.8455446732778, 1875.681134207188, 1923.144200830617, 1926.5280006398523, 1936.390037138297, 831.2975535672347, 890.6749036601594, 924.7641851327868, 924.8174308631624, 934.0099494535814, 19431.611367772566, 20393.18427262893, 20819.791802081785, 20981.790182097982, 21078.89460552718, 899.9055099214619, 931.4411353188033, 958.9232861371073, 980.7793246519618, 981.4994352880083, 10425.687229383131, 11090.279131856403, 11112.888871111347, 11124.499397526968, 11178.832317515244, 589326.0, 594720.0, 598906.0054699698, 623634.0, 624721.0, 84685.15314846773, 107719.0, 121416.78583214231, 134282.0, 144981.27509362384, 870.9782255443664, 931.4318265857821, 946.753844000551, 946.976325591852, 948.3977674176947, 18115.0, 19182.328618498286, 19572.510687232363, 19603.80396196077, 19684.803151968295, 52289.21566176586, 53022.0, 54277.72861135669, 54987.45012549823, 56381.71809140928, 60632.393676062675, 78147.0, 79841.4031719374, 82191.0, 82957.35715321105, 7772.84454310922, 7809.789482630202, 8218.383621228551, 8237.83524329522, 8252.546109964009, 9023.548822558978, 12015.579454719043, 12729.108962372546, 12736.0, 12949.028822838136, 11109.777804443704, 11157.88842111601, 11326.660200194008, 11446.484908179318, 11549.769004620028, 11036.227464077438, 11088.0, 11131.276467029616, 11403.714907127389, 11511.827322590336, 11354.829677554679, 11365.659030229106, 11694.88305116972, 11749.353785541878, 11770.58802941893, 7144.285571442814, 7305.727065121696, 7458.701651934079, 7865.0, 8215.958920205361, 8187.672493100209, 9649.565769540248, 10859.0, 11291.717707057061, 11658.06735461178], "value": 9409.367385791506, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1447.6091455307064, 1485.8737007354375, 1618.3041292244338, 1761.7005109131446, 1808.6563552924952, 8161.428699991003, 8574.35692323075, 8574.614142363593, 8598.0112287087, 8605.182507661777, 7513.849723005536, 7734.38124950004, 7746.070471543423, 8133.2680058794695, 8403.95798021009, 2306.5041016181517, 2345.7067866516686, 2348.8708121053337, 2356.39911822485, 2356.728976167737, 2344.109238489372, 2363.917262895799, 2371.9407014824683, 2394.95210095798, 2397.220903206456, 2060.701198326249, 2139.60417322795, 2249.1116009176367, 2332.0671731307443, 2334.6031174700342, 2183.683365911942, 2184.737831460235, 2186.0272178880405, 2187.6499760038478, 2191.60551100801, 2093.6231478333893, 2328.4528135888054, 2339.9064037438484, 2366.065404165345, 2366.372911178539, 8348.749537513884, 8365.665373385058, 8444.366672499526, 8456.74629761102, 8469.110743371919, 5080.669756465818, 5245.737713114368, 5245.86885327866, 5246.186841039645, 5256.106461901448, 3409.573803274591, 3501.6148223695373, 3544.6987006104514, 3555.306715190539, 3562.447820587813, 1025.2310766924807, 1058.2856571814073, 1084.9132069434427, 1088.37418484371, 1100.2188446203206, 9261.907380926172, 11141.108711303077, 11142.387168705718, 11143.275687080395, 11143.554257829757, 4619.9769001155455, 4634.165850146938, 4809.855704328876, 4824.927626085611, 4830.106430310404, 8757.47455152693, 8848.557572121368, 8859.468431894107, 8860.64557417709, 8865.157810008039, 5212.661177023481, 5215.973920130451, 5222.817201398009, 5226.007058658843, 5227.843164705065, 4615.30770384445, 4801.51984801517, 4849.151398505247, 4851.708897466164, 4903.019396120719, 3506.1234691327168, 3540.2034542227793, 3577.749557531, 3607.981960090183, 3633.16437219441, 1123.5449642894641, 1126.55238019145, 1130.2879186112696, 1142.731458107344, 1144.3991904250336, 830.7009476588423, 842.0526907229367, 863.6977058029639, 923.8660394242931, 936.2790651198675, 5586.832395028156, 5596.412376700469, 5669.461401166883, 5860.941390586125, 5950.988331983486, 435.8975640724427, 455.2124824054404, 455.2556570008061, 465.29507795689557, 465.78108289104745, 3897.5968651285516, 3924.843006279774, 3940.1776008497745, 3982.542007669097, 4020.075382662004, 9872.753181170385, 10214.029667181605, 10343.896561034293, 10344.120749736207, 10376.740581485523, 10306.948465257776, 10308.32995855267, 10310.0, 10320.96790320962, 10329.0, 7782.377409807202, 7945.920540794518, 7949.960250198827, 7958.482698624571, 7994.5203287802915, 4619.376384188092, 5487.670739755627, 5667.1782591524825, 5824.5631577631, 5846.912296315644, 9374.718758437259, 9542.809143816947, 9585.329026968324, 9598.85601715961, 9605.279604029865, 9129.041450647586, 9555.856662149934, 9567.425954442755, 9574.138327550554, 9580.712578622653, 7194.676239569337, 7354.485186036926, 7361.558306501627, 7367.5211111277595, 7378.372838308804, 7856.175101614248, 7935.801604959922, 7947.8807817883935, 7962.800929976797, 7985.960070199611, 6160.815175544741, 7092.219855815753, 7279.963600181965, 7297.781066568012, 7317.195108538163, 6372.884745169669, 6728.259891411843, 7106.218315985134, 7144.464165187528, 7187.173475050227, 6670.633115178712, 6745.460363171032, 6840.3501667341525, 6847.589144651337, 6855.862882742417, 4922.409310882717, 4942.357493525818, 4958.8760280993265, 4981.252812078073, 4988.0, 4882.096812089775, 4894.437139728977, 4917.975410122926, 5058.089543882136, 5063.924041139459], "value": 4343.926511509778, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.74336468860355, 303.91034644779785, 306.2176139962395, 312.31914426550117, 316.7181208724234, 5065.898682026357, 5136.2809206711045, 5182.922256166161, 5279.894402111955, 5348.759305831237, 4836.516348365161, 5169.043726910515, 5313.601479889005, 5336.546393556552, 5336.626436149471, 3183.5514840747405, 4063.5936406359274, 4068.532118806331, 4104.774237416941, 4134.503859536845, 3320.3027364253417, 3851.826667799956, 4108.897277568055, 4130.545639979615, 4134.751914885116, 3552.2717842842158, 4024.798760062, 4030.858919937803, 4054.5337286212166, 4064.1465292288653, 3786.4131059685656, 3936.114374265796, 3984.641382275609, 3999.3601023836336, 4100.856470023565, 2926.736593706555, 2996.651506821917, 3354.1614596350914, 3518.733256027828, 3524.9118772030647, 4800.543948324904, 4922.80308787648, 4968.7018778873025, 5157.613179011589, 5199.6360254782185, 4137.731047481904, 4257.808398622069, 4358.254299791143, 4506.752128632924, 4669.626429885601, 4359.32430473274, 5549.445055494459, 5554.305711786027, 5599.188117722907, 5611.915821262684, 3563.6436356364416, 3597.5323207983056, 3819.4652748615217, 3840.52432548544, 3864.7101467390057, 4946.629002824802, 5225.372955245395, 5822.33043200029, 5833.416658334175, 5868.4424979626865, 3889.630485103911, 3892.3966785148345, 3918.1575961168487, 3945.349017412152, 3947.585503522089, 3810.447485114643, 3883.262180185756, 3965.0373065332847, 3984.601539845992, 3985.461962635065, 4349.3475978603465, 4409.470863496401, 4442.955905362237, 4449.065696203768, 4551.749653769009, 5163.845084647466, 5336.839894803162, 5565.666060036411, 5638.577106717013, 5644.407337229532, 1227.2943057741834, 1228.8218208359738, 1239.3617287097152, 1281.8654041325713, 1294.70869054462, 805.806606414468, 819.6598411659214, 860.5267103093273, 878.8198419324024, 889.7019498467956, 4133.762893034604, 4667.579917807414, 4847.975760121177, 4884.9511504884495, 4970.577500912391, 5280.788768449219, 5322.866928326823, 5361.12360673761, 5410.97294513525, 5440.238366628675, 4287.656987440997, 4529.954700452953, 4531.614812740954, 4629.20405267684, 4909.514244886171, 2426.526827268666, 2464.53173896959, 2821.6190814239817, 2837.1063115118673, 2871.6984716604875, 5046.949530504648, 5341.0, 5627.352854421794, 5630.465105814941, 5640.32316122068, 4792.95207047932, 5123.976029468339, 5304.893902121936, 5506.504414602705, 5998.0, 5346.198070289488, 5840.8539786504825, 6049.909251361321, 6067.757289708362, 6121.622634907199, 5492.489565369556, 5833.724847309468, 5837.3870743571315, 5849.981756294072, 5864.633860018206, 5255.448177941261, 5352.875896061871, 5480.506754392124, 5715.39988301239, 5747.798827041033, 5278.709670968134, 5615.522680572197, 5992.730327135377, 5996.790112346047, 6006.579539432198, 3541.972827879989, 3912.3544615138185, 3996.4404983302943, 4025.879223623296, 4065.9428548576993, 4986.850394488171, 5494.928488944617, 5727.852055479195, 5787.768489260382, 5820.039693450533, 4826.927596086131, 5183.611229157753, 5254.527092561688, 5286.603504737083, 5453.236546883519, 5084.339035925306, 5370.764724113397, 5656.3495198051105, 5660.405657405913, 5665.830025099089, 5126.512981266774, 5272.367315922113, 5505.366882808368, 5559.498225798326, 5605.085355615139, 5574.470425309589, 5635.718214089361, 5637.43625637433, 5649.774009039593, 5677.801276955287, 5522.9447705524035, 5527.8894422112135, 5531.917021244765, 5540.168974653834, 5549.916751248654], "value": 4034.0560245768143, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.1529150937745, 689.2934741889562, 689.551791335632, 735.793977686248, 774.0208636075362, 14003.719925601494, 14464.855351446482, 14467.638309042253, 14469.855301446982, 14480.203588802611, 12756.617301480946, 14236.430542778277, 14769.409223631043, 14806.851931480682, 15114.093154410742, 516.9663971841837, 651.853333000076, 654.6366766444621, 813.4184058398245, 813.556611646654, 668.2548957911941, 783.1893989720612, 808.1474044882647, 822.3174764945081, 825.455199568285, 860.6428332242112, 864.2912811494577, 865.7056600755742, 866.0257210638032, 881.0132651430407, 664.8271449423137, 861.7371701631, 862.348926560446, 862.4307956748547, 862.9050804411572, 509.7629602234978, 608.8508315462695, 637.4517914593446, 641.2593454559991, 642.4539141729539, 14229.359678814479, 14278.64303392413, 14281.928590357085, 14284.500042498566, 14304.284785760774, 12586.81119783204, 12749.0, 12775.552855650101, 12943.417546210443, 13100.672483187902, 12124.27254364741, 12250.114375509727, 12266.632001039983, 12293.970710195823, 12474.0, 3659.890203293879, 3669.6243832563837, 3789.0, 4338.674599405026, 4348.652107831397, 61283.69358153225, 62141.6892915537, 77878.0, 80036.19963800345, 81849.59075204645, 8705.39062265648, 10382.844257336146, 10463.162946964225, 10502.317349372266, 10517.52671129801, 19655.508612284808, 19910.0, 19947.0, 20125.698114528295, 20173.798262017193, 7331.303526165006, 7497.737579184703, 7641.197674244236, 7652.885206721904, 7753.6123193840085, 4088.468499095158, 4167.604077612622, 4175.665946724254, 4257.467816522934, 4370.781460926941, 3400.285939952638, 3435.124043368925, 3492.511048453219, 3501.4047611906035, 3541.0, 648.4423395879539, 653.5523166630873, 656.4813797100237, 664.9601023938579, 667.3693359775064, 609.774383478116, 633.7560039384778, 662.1326062857672, 666.1706175811146, 683.7982795075465, 8754.774331593582, 8806.427582207136, 9170.349337119249, 9232.676856309998, 9276.628934842665, 565.6481010385182, 593.3621357041199, 652.7911068458047, 670.0251134599147, 670.4334837062675, 3806.4100064489494, 3988.6410223079483, 4344.196323680129, 4433.467983841895, 4456.487503937024, 76422.61788691132, 91733.0, 101984.49007754914, 111186.0, 123392.0, 106057.46971265094, 111179.88820111695, 111199.0, 111482.44258778654, 112435.87564124417, 85946.57026714826, 92864.0, 94138.52930735302, 100931.49534252282, 100944.0, 8595.52724600153, 8620.0, 8704.91295087041, 8770.298376130022, 8839.46963182211, 59518.70240648769, 59565.51086222879, 60171.0, 60743.0, 63405.365946339945, 72928.0, 77563.2243677556, 79521.07183922458, 80058.59970700109, 91336.08663913276, 10634.73413164677, 10742.677719668422, 10955.65852415021, 11177.608783692856, 11198.608048718255, 11829.526818927146, 11968.341741204318, 12027.699307517381, 12400.13199076056, 12703.42834572465, 11167.593883865498, 11746.412679365827, 12175.0, 12473.126881118234, 12804.487820487077, 12032.518699251932, 12639.810402844148, 12969.221846689228, 13099.803502947654, 13421.865781342452, 11838.940805295919, 13470.393832277767, 13501.189928604315, 13632.0, 13675.931620341835, 9938.602455901972, 10736.248462607855, 10825.40460274661, 10837.512311946139, 11138.443077846237, 9081.0, 9913.65302214419, 10933.83599245996, 10984.725381865517, 11032.28290161137], "value": 7190.4529103434015, "unit": "score"}], "name": "dromaeo_css", "value": 7120.8700075576135, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 111.0, 113.0, 113.0, 109.0, 105.0, 113.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 137.0, 157.0, 161.0, 109.0, 112.0, 120.0, 106.0, 145.0, 111.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 181.0, 198.0, 256.0, 195.0, 183.0, 182.0, 184.0, 184.0, 184.0], "value": 192.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 60.0, 88.0, 64.0, 58.0, 60.0, 60.0, 82.0, 58.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 92.0, 69.0, 67.0, 70.0, 89.0, 89.0, 99.0, 123.0], "value": 85.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 85.0, 88.0, 84.0, 88.0, 84.0, 88.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 108.0, 127.0, 137.0, 87.0, 132.0, 98.0, 122.0, 137.0, 147.0], "value": 118.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 79.0, 80.0, 84.0, 81.0, 83.0, 83.0, 80.0, 89.0], "value": 82.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 55.0, 55.0, 56.0, 54.0, 58.0, 58.0, 56.0, 55.0, 57.0], "value": 56.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 72.0, 76.0, 74.0, 70.0, 71.0, 71.0, 71.0, 72.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 84.0, 83.0, 85.0, 84.0, 84.0, 84.0, 82.0, 85.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 142.0, 137.0, 137.0, 153.0, 165.0, 161.0, 196.0, 155.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 173.0, 165.0, 163.0, 165.0, 165.0, 165.0, 165.0, 165.0, 166.0], "value": 165.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 66.0, 65.0, 66.0, 66.0, 65.0, 66.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1472.4, "alertThreshold": 2.0}]} 08:43:41 INFO - Return code: 0 08:43:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:43:41 INFO - # TBPL SUCCESS # 08:43:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:43:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:43:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:43:41 INFO - 2017-01-22 08:43:41.886 screenresolution[1676:17411] starting screenresolution argv=screenresolution get 08:43:41 INFO - 2017-01-22 08:43:41.888 screenresolution[1676:17411] Display 0: 1600x1200x32@60 08:43:41 INFO - 2017-01-22 08:43:41.895 screenresolution[1677:17414] starting screenresolution argv=screenresolution list 08:43:41 INFO - Available Modes on Display 0 08:43:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:43:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:43:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:43:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:43:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:43:42 INFO - Intel Iris: 08:43:42 INFO - Chipset Model: Intel Iris 08:43:42 INFO - Type: GPU 08:43:42 INFO - Bus: Built-In 08:43:42 INFO - VRAM (Dynamic, Max): 1536 MB 08:43:42 INFO - Vendor: Intel (0x8086) 08:43:42 INFO - Device ID: 0x0a2e 08:43:42 INFO - Revision ID: 0x0009 08:43:42 INFO - Displays: 08:43:42 INFO - Display: 08:43:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:43:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:43:42 INFO - Display Serial Number: 1600x1200 60 08:43:42 INFO - Main Display: Yes 08:43:42 INFO - Mirror: Off 08:43:42 INFO - Online: Yes 08:43:42 INFO - Rotation: Supported 08:43:42 INFO - Return code: 0 08:43:42 INFO - Running post-action listener: _resource_record_post_action 08:43:42 INFO - [mozharness: 2017-01-22 16:43:42.138667Z] Finished run-tests step (success) 08:43:42 INFO - Running post-run listener: _resource_record_post_run 08:43:42 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 33520640; Write bytes: 663815680; Read time: 311; Write time: 1902 08:43:42 INFO - TinderboxPrint: CPU usage
53.3% 08:43:42 INFO - TinderboxPrint: I/O read bytes / time
33,520,640 / 311 08:43:42 INFO - TinderboxPrint: I/O write bytes / time
663,815,680 / 1,902 08:43:42 INFO - TinderboxPrint: CPU idle
2,260.5 (46.7%) 08:43:42 INFO - TinderboxPrint: CPU system
966.8 (20.0%) 08:43:42 INFO - TinderboxPrint: CPU user
1,618.3 (33.4%) 08:43:42 INFO - TinderboxPrint: Swap in / out
381,087,744 / 0 08:43:42 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 337287680; Write bytes: 337024512; Read time: 26625; Write time: 650 08:43:42 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 32594944; Write bytes: 309067776; Read time: 298; Write time: 1200 08:43:42 INFO - Running post-run listener: _upload_blobber_files 08:43:42 INFO - Blob upload gear active. 08:43:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:43:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:43:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:43:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:43:43 INFO - (blobuploader) - INFO - Open directory for files ... 08:43:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:43:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:43:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:43:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:43:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:43:44 INFO - (blobuploader) - INFO - Done attempting. 08:43:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:43:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:43:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:43:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:43:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:43:45 INFO - (blobuploader) - INFO - Done attempting. 08:43:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:43:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:43:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:43:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:43:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:43:48 INFO - (blobuploader) - INFO - Done attempting. 08:43:48 INFO - (blobuploader) - INFO - Iteration through files over. 08:43:48 INFO - Return code: 0 08:43:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:43:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:43:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b7b3dbefe9fb3b14aa6d34c7cc6baf2fca3bcb77db15f6899f6fa92001fb8058c2c1401938e1e226961d0fb2fa993c874cc1a25ad7d9b3fe5e3e925695613010", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8363ac6009993df9f998ec2e8c0018177bf0ba8a3d50f67db7a3f772a95ea341ba39c7fd3948041d519357b2931f10b00a0f299538f4d157658aa5477933048", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6901ec814824bae5c30bb7977c0a7847be73b1930505a53e902a6b7ac87fb5ebafde947645c8e2fb07ef920fbc5aeeaf142425f62c2a0624fce93616d1531fdc"} 08:43:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:43:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:43:48 INFO - Contents: 08:43:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b7b3dbefe9fb3b14aa6d34c7cc6baf2fca3bcb77db15f6899f6fa92001fb8058c2c1401938e1e226961d0fb2fa993c874cc1a25ad7d9b3fe5e3e925695613010", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8363ac6009993df9f998ec2e8c0018177bf0ba8a3d50f67db7a3f772a95ea341ba39c7fd3948041d519357b2931f10b00a0f299538f4d157658aa5477933048", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6901ec814824bae5c30bb7977c0a7847be73b1930505a53e902a6b7ac87fb5ebafde947645c8e2fb07ef920fbc5aeeaf142425f62c2a0624fce93616d1531fdc"} 08:43:48 INFO - Running post-run listener: copy_logs_to_upload_dir 08:43:48 INFO - Copying logs to upload dir... 08:43:48 INFO - mkdir: /builds/slave/test/build/upload/logs 08:43:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.516169 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-01-22 08:43:48.088608) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-22 08:43:48.092446) ========= 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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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/b7b3dbefe9fb3b14aa6d34c7cc6baf2fca3bcb77db15f6899f6fa92001fb8058c2c1401938e1e226961d0fb2fa993c874cc1a25ad7d9b3fe5e3e925695613010", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8363ac6009993df9f998ec2e8c0018177bf0ba8a3d50f67db7a3f772a95ea341ba39c7fd3948041d519357b2931f10b00a0f299538f4d157658aa5477933048", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6901ec814824bae5c30bb7977c0a7847be73b1930505a53e902a6b7ac87fb5ebafde947645c8e2fb07ef920fbc5aeeaf142425f62c2a0624fce93616d1531fdc"} build_url:https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011146 build_url: 'https://queue.taskcluster.net/v1/task/evSNjfPRTQeOWyJ5q01F_A/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/b7b3dbefe9fb3b14aa6d34c7cc6baf2fca3bcb77db15f6899f6fa92001fb8058c2c1401938e1e226961d0fb2fa993c874cc1a25ad7d9b3fe5e3e925695613010", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8363ac6009993df9f998ec2e8c0018177bf0ba8a3d50f67db7a3f772a95ea341ba39c7fd3948041d519357b2931f10b00a0f299538f4d157658aa5477933048", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6901ec814824bae5c30bb7977c0a7847be73b1930505a53e902a6b7ac87fb5ebafde947645c8e2fb07ef920fbc5aeeaf142425f62c2a0624fce93616d1531fdc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-22 08:43:48.126923) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-22 08:43:48.127241) ========= 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.BLouFN0QFJ/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.CbJSe8z4AR/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.005055 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-22 08:43:48.176752) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-22 08:43:48.177158) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-22 08:43:48.177467) ========= ========= Total master_lag: 0.19 =========