builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0359 starttime: 1479148268.64 results: success (0) buildid: 20161114074144 builduid: c0ce765cf2c74afa85cbf7cb62a44e31 revision: 8cdff71808a0e11361bca7f218658c4343c730ca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 10:31:08.635424) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 10:31:08.635905) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 10:31:08.636198) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 10:31:08.661809) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:31:08.662219) ========= 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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-14 10:31:08-- 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% 19.9M=0.001s 2016-11-14 10:31:08 (19.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127617 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:31:08.811558) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:31:08.811884) ========= 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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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.023059 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:31:08.859994) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 10:31:08.860357) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8cdff71808a0e11361bca7f218658c4343c730ca --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 8cdff71808a0e11361bca7f218658c4343c730ca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-14 10:31:08,943 truncating revision to first 12 chars 2016-11-14 10:31:08,943 Setting DEBUG logging. 2016-11-14 10:31:08,943 attempt 1/10 2016-11-14 10:31:08,943 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8cdff71808a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 10:31:09,838 unpacking tar archive at: mozilla-aurora-8cdff71808a0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.131464 ========= master_lag: 0.35 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 10:31:10.340996) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 10:31:10.346263) ========= script_repo_revision: 8cdff71808a0e11361bca7f218658c4343c730ca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 10:31:10.346789) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 10:31:10.347096) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 10:31:10.367726) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-14 10:31:10.368135) ========= /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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 10:31:10 INFO - MultiFileLogger online at 20161114 10:31:10 in /builds/slave/test 10:31:10 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 10:31:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:31:10 INFO - {'append_to_log': False, 10:31:10 INFO - 'base_work_dir': '/builds/slave/test', 10:31:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:31:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:31:10 INFO - 'branch': 'Mozilla-Aurora', 10:31:10 INFO - 'buildbot_json_path': 'buildprops.json', 10:31:10 INFO - 'config_files': ('talos/mac_config.py',), 10:31:10 INFO - 'default_actions': ('clobber', 10:31:10 INFO - 'read-buildbot-config', 10:31:10 INFO - 'download-and-extract', 10:31:10 INFO - 'populate-webroot', 10:31:10 INFO - 'create-virtualenv', 10:31:10 INFO - 'install', 10:31:10 INFO - 'run-tests'), 10:31:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:31:10 INFO - 'download_minidump_stackwalk': True, 10:31:10 INFO - 'download_symbols': 'ondemand', 10:31:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:31:10 INFO - 'tooltool.py': '/tools/tooltool.py', 10:31:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:31:10 INFO - '/tools/misc-python/virtualenv.py')}, 10:31:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:31:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:31:10 INFO - 'installer_path': 'installer.exe', 10:31:10 INFO - 'log_level': 'info', 10:31:10 INFO - 'log_name': 'talos', 10:31:10 INFO - 'log_to_console': True, 10:31:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:31:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:31:10 INFO - 'opt_config_files': (), 10:31:10 INFO - 'pip_index': False, 10:31:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:31:10 INFO - 'cmd': ('bash', 10:31:10 INFO - '-c', 10:31:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:31:10 INFO - 'enabled': True, 10:31:10 INFO - 'halt_on_failure': False, 10:31:10 INFO - 'name': 'check_screen_resolution'},), 10:31:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:31:10 INFO - 'cmd': ('bash', 10:31:10 INFO - '-c', 10:31:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:31:10 INFO - 'enabled': True, 10:31:10 INFO - 'halt_on_failure': False, 10:31:10 INFO - 'name': 'check_screen_resolution'},), 10:31:10 INFO - 'run_cmd_checks_enabled': True, 10:31:10 INFO - 'sps_profile': False, 10:31:10 INFO - 'sps_profile_interval': 0, 10:31:10 INFO - 'suite': 'dromaeojs', 10:31:10 INFO - 'system_bits': '32', 10:31:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:31:10 INFO - 'title': 't-yosemite-r7-0359', 10:31:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:31:10 INFO - 'use_talos_json': True, 10:31:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:31:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:31:10 INFO - 'work_dir': 'build'} 10:31:10 INFO - [mozharness: 2016-11-14 18:31:10.575884Z] Running clobber step. 10:31:10 INFO - Running pre-action listener: _resource_record_pre_action 10:31:10 INFO - Running main action method: clobber 10:31:10 INFO - rmtree: /builds/slave/test/build 10:31:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:31:12 INFO - Running post-action listener: _resource_record_post_action 10:31:12 INFO - [mozharness: 2016-11-14 18:31:12.906011Z] Finished clobber step (success) 10:31:12 INFO - [mozharness: 2016-11-14 18:31:12.906197Z] Running read-buildbot-config step. 10:31:12 INFO - Running pre-action listener: _resource_record_pre_action 10:31:12 INFO - Running main action method: read_buildbot_config 10:31:12 INFO - Using buildbot properties: 10:31:12 INFO - { 10:31:12 INFO - "project": "", 10:31:12 INFO - "product": "firefox", 10:31:12 INFO - "script_repo_revision": "production", 10:31:12 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:31:12 INFO - "repository": "", 10:31:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:31:12 INFO - "buildid": "20161114074144", 10:31:12 INFO - "pgo_build": "False", 10:31:12 INFO - "basedir": "/builds/slave/test", 10:31:12 INFO - "buildnumber": 25, 10:31:12 INFO - "slavename": "t-yosemite-r7-0359", 10:31:12 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 10:31:12 INFO - "platform": "yosemite_r7", 10:31:12 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:31:12 INFO - "branch": "mozilla-aurora", 10:31:12 INFO - "repo_path": "releases/mozilla-aurora", 10:31:12 INFO - "revision": "8cdff71808a0e11361bca7f218658c4343c730ca", 10:31:12 INFO - "stage_platform": "macosx64", 10:31:12 INFO - "builduid": "c0ce765cf2c74afa85cbf7cb62a44e31", 10:31:12 INFO - "slavebuilddir": "test" 10:31:12 INFO - } 10:31:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 10:31:12 INFO - Running post-action listener: _resource_record_post_action 10:31:12 INFO - [mozharness: 2016-11-14 18:31:12.909369Z] Finished read-buildbot-config step (success) 10:31:12 INFO - [mozharness: 2016-11-14 18:31:12.909511Z] Running download-and-extract step. 10:31:12 INFO - Running pre-action listener: _resource_record_pre_action 10:31:12 INFO - Running main action method: download_and_extract 10:31:12 INFO - mkdir: /builds/slave/test/build/tests 10:31:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:12 INFO - trying https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 10:31:12 INFO - Downloading https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/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 10:31:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/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 10:31:13 INFO - Downloaded 1199 bytes. 10:31:13 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:31:13 INFO - Using the following test package requirements: 10:31:13 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:31:13 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:13 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 10:31:13 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:13 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 10:31:13 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:13 INFO - u'jsshell-mac.zip'], 10:31:13 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:13 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 10:31:13 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:31:13 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:13 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 10:31:13 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:13 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 10:31:13 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:13 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 10:31:13 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:13 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 10:31:13 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:31:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:31:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:31:13 INFO - Fetch https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:31:14 INFO - Http code: 200 10:31:14 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 10:31:14 INFO - x-amz-cf-id: u_Kyzxq-Y097t6TKfQj4Z3gF9IQ5fb5MS170OwWaqIxJnhiwnuidBg== 10:31:14 INFO - x-amz-version-id: qEZpoxVrb8K_lOtz8d1xXpN4NyRiiL2Q 10:31:14 INFO - Expected file size: 16327509 10:31:14 INFO - Obtained file size: 16327509 10:31:21 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 10:31:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:31:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:31:21 INFO - Fetch https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:31:22 INFO - Http code: 200 10:31:22 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 10:31:22 INFO - x-amz-cf-id: 5iekoydm9X8DZLTl-CAoYXwPULPqnAvjNTN1pGCsJK3_O5qxaRzVTQ== 10:31:22 INFO - x-amz-version-id: qEZpoxVrb8K_lOtz8d1xXpN4NyRiiL2Q 10:31:22 INFO - Expected file size: 16327509 10:31:22 INFO - Obtained file size: 16327509 10:31:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 10:31:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:31:29 INFO - Fetch https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 10:31:30 INFO - Http code: 200 10:31:30 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 10:31:30 INFO - x-amz-cf-id: -1FmNonME0S_tCYmiHxPplni8PnO2DwzjTvCFXZTMk-QweUcVLaNtA== 10:31:30 INFO - x-amz-version-id: jXsupoNyJ.YzlDUqzWygU8BmOV_JFB8G 10:31:30 INFO - Expected file size: 14409038 10:31:30 INFO - Obtained file size: 14409038 10:31: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')]} 10:31:30 INFO - trying https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:31:30 INFO - Downloading https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 10:31:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/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 10:31:33 INFO - Downloaded 114362341 bytes. 10:31:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:31:33 INFO - mkdir: /builds/slave/test/properties 10:31:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:31:33 INFO - Writing to file /builds/slave/test/properties/build_url 10:31:33 INFO - Contents: 10:31:33 INFO - build_url:https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:31:34 INFO - Running post-action listener: _resource_record_post_action 10:31:34 INFO - Running post-action listener: set_extra_try_arguments 10:31:34 INFO - [mozharness: 2016-11-14 18:31:34.207061Z] Finished download-and-extract step (success) 10:31:34 INFO - [mozharness: 2016-11-14 18:31:34.207212Z] Running populate-webroot step. 10:31:34 INFO - Running pre-action listener: _resource_record_pre_action 10:31:34 INFO - Running main action method: populate_webroot 10:31:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:31:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:31:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:31:34 INFO - '--tppagecycles', 10:31:34 INFO - '7'], 10:31:34 INFO - 'tests': ['tsvgm']}}, 10:31:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:31:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:31:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:31:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:31:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:31:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:31:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:34 INFO - 'talos_options': ['--disable-e10s'], 10:31:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:31:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:31:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:34 INFO - 'talos_options': ['--disable-e10s'], 10:31:34 INFO - 'tests': ['damp', 'tps']}, 10:31:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:34 INFO - 'tests': ['damp', 'tps']}, 10:31:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:31:34 INFO - 'tests': ['dromaeo_dom']}, 10:31:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:31:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:31:34 INFO - 'tests': ['basic_compositor_video']}, 10:31:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:31:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:31:34 INFO - 'tests': ['a11yr', 10:31:34 INFO - 'ts_paint', 10:31:34 INFO - 'tpaint', 10:31:34 INFO - 'sessionrestore', 10:31:34 INFO - 'sessionrestore_no_auto_restore', 10:31:34 INFO - 'tabpaint']}, 10:31:34 INFO - 'other-e10s': {'tests': ['a11yr', 10:31:34 INFO - 'ts_paint', 10:31:34 INFO - 'tpaint', 10:31:34 INFO - 'sessionrestore', 10:31:34 INFO - 'sessionrestore_no_auto_restore', 10:31:34 INFO - 'tabpaint']}, 10:31:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:31:34 INFO - 'ts_paint', 10:31:34 INFO - 'tpaint', 10:31:34 INFO - 'sessionrestore', 10:31:34 INFO - 'sessionrestore_no_auto_restore', 10:31:34 INFO - 'tabpaint']}, 10:31:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:31:34 INFO - 'ts_paint', 10:31:34 INFO - 'tpaint', 10:31:34 INFO - 'sessionrestore', 10:31:34 INFO - 'sessionrestore_no_auto_restore', 10:31:34 INFO - 'tabpaint']}, 10:31:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:31:34 INFO - 'tests': ['a11yr', 10:31:34 INFO - 'ts_paint', 10:31:34 INFO - 'tpaint', 10:31:34 INFO - 'sessionrestore', 10:31:34 INFO - 'sessionrestore_no_auto_restore', 10:31:34 INFO - 'tabpaint']}, 10:31:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:31:34 INFO - 'tests': ['a11yr', 10:31:34 INFO - 'ts_paint', 10:31:34 INFO - 'tpaint', 10:31:34 INFO - 'sessionrestore', 10:31:34 INFO - 'sessionrestore_no_auto_restore', 10:31:34 INFO - 'tabpaint']}, 10:31:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:31:34 INFO - 'tests': ['tsvgx', 10:31:34 INFO - 'tsvgr_opacity', 10:31:34 INFO - 'tart', 10:31:34 INFO - 'tscrollx', 10:31:34 INFO - 'cart']}, 10:31:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:31:34 INFO - 'tsvgr_opacity', 10:31:34 INFO - 'tart', 10:31:34 INFO - 'tscrollx', 10:31:34 INFO - 'cart']}, 10:31:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:34 INFO - 'talos_options': ['--disable-e10s'], 10:31:34 INFO - 'tests': ['tp5o']}, 10:31:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:34 INFO - 'tests': ['tp5o']}, 10:31:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:34 INFO - 'talos_options': ['--disable-e10s', 10:31:34 INFO - '--xperf_path', 10:31:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:31:34 INFO - 'tests': ['tp5n']}, 10:31:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:34 INFO - 'talos_options': ['--xperf_path', 10:31:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:31:34 INFO - 'tests': ['tp5n']}}, 10:31:34 INFO - 'talos.zip': {'path': '', 10:31:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:31:34 INFO - Running post-action listener: _resource_record_post_action 10:31:34 INFO - [mozharness: 2016-11-14 18:31:34.226017Z] Finished populate-webroot step (success) 10:31:34 INFO - [mozharness: 2016-11-14 18:31:34.226147Z] Running create-virtualenv step. 10:31:34 INFO - Running pre-action listener: _resource_record_pre_action 10:31:34 INFO - Running main action method: create_virtualenv 10:31:34 INFO - Creating virtualenv /builds/slave/test/build/venv 10:31:34 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 10:31:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:31:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:31:34 INFO - Using real prefix '/tools/python27' 10:31:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:31:35 INFO - Installing distribute.............................................................................................................................................................................................done. 10:31:37 INFO - Installing pip.................done. 10:31:38 INFO - Return code: 0 10:31:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:31:38 INFO - Reading from file tmpfile_stdout 10:31:38 INFO - Output received: 10:31:38 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) 10:31:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a9a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d35ace60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aed30>, '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 0x101714db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018a6b20>, '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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 10:31:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:31:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pXEUoQ5YPo/Render', 10:31:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:38 INFO - 'HOME': '/Users/cltbld', 10:31:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:38 INFO - 'LOGNAME': 'cltbld', 10:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:38 INFO - 'MOZ_NO_REMOTE': '1', 10:31:38 INFO - 'NO_EM_RESTART': '1', 10:31:38 INFO - 'PAGER': '/bin/cat', 10:31:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:38 INFO - 'PWD': '/builds/slave/test', 10:31:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:38 INFO - 'SHELL': '/bin/bash', 10:31:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVr3WhFkuy/Listeners', 10:31:38 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 10:31:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:38 INFO - 'USER': 'cltbld', 10:31:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:38 INFO - 'XPC_FLAGS': '0x0', 10:31:38 INFO - 'XPC_SERVICE_NAME': '0', 10:31:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:38 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 10:31:38 INFO - Cleaning up... 10:31:38 INFO - Return code: 0 10:31:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101a9a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d35ace60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aed30>, '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 0x101714db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018a6b20>, '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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 10:31:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:31:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pXEUoQ5YPo/Render', 10:31:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:38 INFO - 'HOME': '/Users/cltbld', 10:31:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:38 INFO - 'LOGNAME': 'cltbld', 10:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:38 INFO - 'MOZ_NO_REMOTE': '1', 10:31:38 INFO - 'NO_EM_RESTART': '1', 10:31:38 INFO - 'PAGER': '/bin/cat', 10:31:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:38 INFO - 'PWD': '/builds/slave/test', 10:31:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:38 INFO - 'SHELL': '/bin/bash', 10:31:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVr3WhFkuy/Listeners', 10:31:38 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 10:31:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:38 INFO - 'USER': 'cltbld', 10:31:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:38 INFO - 'XPC_FLAGS': '0x0', 10:31:38 INFO - 'XPC_SERVICE_NAME': '0', 10:31:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:38 INFO - Downloading/unpacking psutil>=3.1.1 10:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:31:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:31:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:31:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:31:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:31:42 INFO - Installing collected packages: psutil 10:31:42 INFO - Running setup.py install for psutil 10:31:42 INFO - building 'psutil._psutil_osx' extension 10:31:42 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 10:31:42 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 10:31:42 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 10:31:42 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 10:31:43 INFO - building 'psutil._psutil_posix' extension 10:31:43 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 10:31:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:31:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:31:43 INFO - ^ 10:31:43 INFO - 1 warning generated. 10:31:43 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 10:31:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:31:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:31:43 INFO - Successfully installed psutil 10:31:43 INFO - Cleaning up... 10:31:43 INFO - Return code: 0 10:31:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:43 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 0x101a9a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d35ace60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aed30>, '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 0x101714db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018a6b20>, '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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 10:31:43 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 10:31:43 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 10:31:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pXEUoQ5YPo/Render', 10:31:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:43 INFO - 'HOME': '/Users/cltbld', 10:31:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:43 INFO - 'LOGNAME': 'cltbld', 10:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:43 INFO - 'MOZ_NO_REMOTE': '1', 10:31:43 INFO - 'NO_EM_RESTART': '1', 10:31:43 INFO - 'PAGER': '/bin/cat', 10:31:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:43 INFO - 'PWD': '/builds/slave/test', 10:31:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:43 INFO - 'SHELL': '/bin/bash', 10:31:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVr3WhFkuy/Listeners', 10:31:43 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 10:31:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:43 INFO - 'USER': 'cltbld', 10:31:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:43 INFO - 'XPC_FLAGS': '0x0', 10:31:43 INFO - 'XPC_SERVICE_NAME': '0', 10:31:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:31:43 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 10:31:43 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 10:31:43 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 10:31:43 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 10:31:43 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 10:31:43 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 10:31:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:31:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:31:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:31:46 INFO - Installing collected packages: mozsystemmonitor 10:31:46 INFO - Running setup.py install for mozsystemmonitor 10:31:46 INFO - Successfully installed mozsystemmonitor 10:31:46 INFO - Cleaning up... 10:31:46 INFO - Return code: 0 10:31:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:31:46 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')]} 10:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:46 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')]} 10:31:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:46 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 0x101a9a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d35ace60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aed30>, '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 0x101714db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018a6b20>, '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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 10:31:46 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 10:31:46 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 10:31:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pXEUoQ5YPo/Render', 10:31:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:46 INFO - 'HOME': '/Users/cltbld', 10:31:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:46 INFO - 'LOGNAME': 'cltbld', 10:31:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:46 INFO - 'MOZ_NO_REMOTE': '1', 10:31:46 INFO - 'NO_EM_RESTART': '1', 10:31:46 INFO - 'PAGER': '/bin/cat', 10:31:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:46 INFO - 'PWD': '/builds/slave/test', 10:31:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:46 INFO - 'SHELL': '/bin/bash', 10:31:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVr3WhFkuy/Listeners', 10:31:46 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 10:31:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:46 INFO - 'USER': 'cltbld', 10:31:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:46 INFO - 'XPC_FLAGS': '0x0', 10:31:46 INFO - 'XPC_SERVICE_NAME': '0', 10:31:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:46 INFO - Downloading/unpacking jsonschema==2.5.1 10:31:46 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 10:31:46 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 10:31:46 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 10:31:46 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 10:31:46 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 10:31:46 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 10:31:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:31:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:31:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:31:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:31:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:31:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:31:49 INFO - Downloading functools32-3.2.3-2.tar.gz 10:31:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:31:49 INFO - warning: no files found matching '*.txt' 10:31:49 INFO - no previously-included directories found matching 'build' 10:31:49 INFO - no previously-included directories found matching 'dist' 10:31:49 INFO - no previously-included directories found matching '.git*' 10:31:49 INFO - Installing collected packages: jsonschema, functools32 10:31:49 INFO - Running setup.py install for functools32 10:31:49 INFO - warning: no files found matching '*.txt' 10:31:49 INFO - no previously-included directories found matching 'build' 10:31:49 INFO - no previously-included directories found matching 'dist' 10:31:49 INFO - no previously-included directories found matching '.git*' 10:31:49 INFO - Successfully installed jsonschema functools32 10:31:49 INFO - Cleaning up... 10:31:49 INFO - Return code: 0 10:31:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:31:49 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')]} 10:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:49 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')]} 10:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:49 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 0x101a9a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d35ace60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aed30>, '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 0x101714db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018a6b20>, '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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 10:31:49 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 10:31:49 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 10:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pXEUoQ5YPo/Render', 10:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:49 INFO - 'HOME': '/Users/cltbld', 10:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:49 INFO - 'LOGNAME': 'cltbld', 10:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:49 INFO - 'MOZ_NO_REMOTE': '1', 10:31:49 INFO - 'NO_EM_RESTART': '1', 10:31:49 INFO - 'PAGER': '/bin/cat', 10:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:49 INFO - 'PWD': '/builds/slave/test', 10:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:49 INFO - 'SHELL': '/bin/bash', 10:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVr3WhFkuy/Listeners', 10:31:49 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 10:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:49 INFO - 'USER': 'cltbld', 10:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:49 INFO - 'XPC_FLAGS': '0x0', 10:31:49 INFO - 'XPC_SERVICE_NAME': '0', 10:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:31:50 INFO - Cleaning up... 10:31:50 INFO - Return code: 0 10:31:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:31:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:50 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 0x101a9a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d35ace60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aed30>, '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 0x101714db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018a6b20>, '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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 10:31:50 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 10:31:50 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 10:31:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pXEUoQ5YPo/Render', 10:31:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:50 INFO - 'HOME': '/Users/cltbld', 10:31:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:50 INFO - 'LOGNAME': 'cltbld', 10:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:50 INFO - 'MOZ_NO_REMOTE': '1', 10:31:50 INFO - 'NO_EM_RESTART': '1', 10:31:50 INFO - 'PAGER': '/bin/cat', 10:31:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:50 INFO - 'PWD': '/builds/slave/test', 10:31:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:50 INFO - 'SHELL': '/bin/bash', 10:31:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVr3WhFkuy/Listeners', 10:31:50 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 10:31:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:50 INFO - 'USER': 'cltbld', 10:31:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:50 INFO - 'XPC_FLAGS': '0x0', 10:31:50 INFO - 'XPC_SERVICE_NAME': '0', 10:31:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:50 INFO - Downloading/unpacking blobuploader==1.2.4 10:31:50 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 10:31:50 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 10:31:50 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 10:31:50 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 10:31:50 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 10:31:50 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 10:31:52 INFO - Downloading blobuploader-1.2.4.tar.gz 10:31:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:31:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:31:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:31:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:31:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:31:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:31:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:31:54 INFO - Downloading docopt-0.6.1.tar.gz 10:31:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:31:54 INFO - Installing collected packages: blobuploader, requests, docopt 10:31:54 INFO - Running setup.py install for blobuploader 10:31:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:31:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:31:54 INFO - Running setup.py install for requests 10:31:54 INFO - Running setup.py install for docopt 10:31:55 INFO - Successfully installed blobuploader requests docopt 10:31:55 INFO - Cleaning up... 10:31:55 INFO - Return code: 0 10:31:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:55 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 0x101a9a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d35ace60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aed30>, '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 0x101714db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018a6b20>, '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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 10:31:55 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 10:31:55 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 10:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pXEUoQ5YPo/Render', 10:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:55 INFO - 'HOME': '/Users/cltbld', 10:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:55 INFO - 'LOGNAME': 'cltbld', 10:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:55 INFO - 'MOZ_NO_REMOTE': '1', 10:31:55 INFO - 'NO_EM_RESTART': '1', 10:31:55 INFO - 'PAGER': '/bin/cat', 10:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:55 INFO - 'PWD': '/builds/slave/test', 10:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:55 INFO - 'SHELL': '/bin/bash', 10:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVr3WhFkuy/Listeners', 10:31:55 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 10:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:55 INFO - 'USER': 'cltbld', 10:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:55 INFO - 'XPC_FLAGS': '0x0', 10:31:55 INFO - 'XPC_SERVICE_NAME': '0', 10:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:31:55 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-n9ozFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:31:55 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-rY6w34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:31:55 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-caEZ7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:31:55 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-R8BpSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:31:55 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-ZMuIu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:31:55 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-7leGv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:31:56 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-rvCPvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:31:56 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-gsLhVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:31:56 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-AXZOOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:31:56 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-T362xD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:31:56 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-JtQOm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:31:56 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-H9XaQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:31:56 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-n9hI5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:31:56 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-TQFt0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:31:56 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-7FNZfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:31:57 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-ZGzQnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:31:57 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-2AP06G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:31:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:31:57 INFO - Running setup.py install for manifestparser 10:31:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:31:57 INFO - Running setup.py install for mozcrash 10:31:57 INFO - Running setup.py install for mozdebug 10:31:57 INFO - Running setup.py install for mozdevice 10:31:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:31:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:31:57 INFO - Running setup.py install for mozfile 10:31:57 INFO - Running setup.py install for mozhttpd 10:31:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Running setup.py install for mozinfo 10:31:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Running setup.py install for mozInstall 10:31:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Running setup.py install for mozleak 10:31:58 INFO - Running setup.py install for mozlog 10:31:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Running setup.py install for moznetwork 10:31:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:31:58 INFO - Running setup.py install for mozprocess 10:31:59 INFO - Running setup.py install for mozprofile 10:31:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:31:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:31:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:31:59 INFO - Running setup.py install for mozrunner 10:31:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:31:59 INFO - Running setup.py install for mozscreenshot 10:31:59 INFO - Running setup.py install for moztest 10:31:59 INFO - Running setup.py install for mozversion 10:31:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:31:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:31:59 INFO - Cleaning up... 10:31:59 INFO - Return code: 0 10:31:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:31:59 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')]} 10:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:59 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')]} 10:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:59 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 0x101a9a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d35ace60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aed30>, '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 0x101714db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018a6b20>, '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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 10:31:59 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 10:31:59 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 10:31:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pXEUoQ5YPo/Render', 10:31:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:59 INFO - 'HOME': '/Users/cltbld', 10:31:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:59 INFO - 'LOGNAME': 'cltbld', 10:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:59 INFO - 'MOZ_NO_REMOTE': '1', 10:31:59 INFO - 'NO_EM_RESTART': '1', 10:31:59 INFO - 'PAGER': '/bin/cat', 10:31:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:59 INFO - 'PWD': '/builds/slave/test', 10:31:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:59 INFO - 'SHELL': '/bin/bash', 10:31:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVr3WhFkuy/Listeners', 10:31:59 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 10:31:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:59 INFO - 'USER': 'cltbld', 10:31:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:59 INFO - 'XPC_FLAGS': '0x0', 10:31:59 INFO - 'XPC_SERVICE_NAME': '0', 10:31:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:32:00 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-DAMqpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:32:00 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-2wNh66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:32:00 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-F8hzmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:32:00 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-EYjySQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:32:00 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-mThPPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:32:00 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-B4DkxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:32:00 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-sOacI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:32:00 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-Y3qywQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:32:00 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)) 10:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:32:00 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-ExkyNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:32:01 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-VnYmQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:32:01 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-Y_4bFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:32:01 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-xQ6VMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:32:01 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-YHfvXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:32:01 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-ZL5QeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:32:01 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-J1TBeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:32:01 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)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:32:01 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-NYbFBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:32:01 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-FmNjrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:32:01 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)) 10:32:01 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)) 10:32:01 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)) 10:32:01 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)) 10:32:01 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)) 10:32:01 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)) 10:32:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:32:01 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 10:32:01 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 10:32:01 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 10:32:01 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 10:32:01 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 10:32:01 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 10:32:04 INFO - Downloading blessings-1.6.tar.gz 10:32:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:32:04 INFO - Installing collected packages: blessings 10:32:04 INFO - Running setup.py install for blessings 10:32:04 INFO - Successfully installed blessings 10:32:04 INFO - Cleaning up... 10:32:04 INFO - Return code: 0 10:32:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:32:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:32:05 INFO - Reading from file tmpfile_stdout 10:32:05 INFO - Current package versions: 10:32:05 INFO - blessings == 1.6 10:32:05 INFO - blobuploader == 1.2.4 10:32:05 INFO - docopt == 0.6.1 10:32:05 INFO - functools32 == 3.2.3-2 10:32:05 INFO - jsonschema == 2.5.1 10:32:05 INFO - manifestparser == 1.1 10:32:05 INFO - mozInstall == 1.12 10:32:05 INFO - mozcrash == 1.0 10:32:05 INFO - mozdebug == 0.1 10:32:05 INFO - mozdevice == 0.48 10:32:05 INFO - mozfile == 1.2 10:32:05 INFO - mozhttpd == 0.7 10:32:05 INFO - mozinfo == 0.9 10:32:05 INFO - mozleak == 0.1 10:32:05 INFO - mozlog == 3.4 10:32:05 INFO - moznetwork == 0.27 10:32:05 INFO - mozprocess == 0.23 10:32:05 INFO - mozprofile == 0.28 10:32:05 INFO - mozrunner == 6.13 10:32:05 INFO - mozscreenshot == 0.1 10:32:05 INFO - mozsystemmonitor == 0.3 10:32:05 INFO - moztest == 0.7 10:32:05 INFO - mozversion == 1.4 10:32:05 INFO - psutil == 3.1.1 10:32:05 INFO - requests == 1.2.3 10:32:05 INFO - wsgiref == 0.1.2 10:32:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:05 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 0x101a9a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d35ace60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aed30>, '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 0x101714db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018a6b20>, '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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 10:32:05 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 10:32:05 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 10:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pXEUoQ5YPo/Render', 10:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:05 INFO - 'HOME': '/Users/cltbld', 10:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:05 INFO - 'LOGNAME': 'cltbld', 10:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:05 INFO - 'MOZ_NO_REMOTE': '1', 10:32:05 INFO - 'NO_EM_RESTART': '1', 10:32:05 INFO - 'PAGER': '/bin/cat', 10:32:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:05 INFO - 'PWD': '/builds/slave/test', 10:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:05 INFO - 'SHELL': '/bin/bash', 10:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVr3WhFkuy/Listeners', 10:32:05 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 10:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:05 INFO - 'USER': 'cltbld', 10:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:05 INFO - 'XPC_FLAGS': '0x0', 10:32:05 INFO - 'XPC_SERVICE_NAME': '0', 10:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 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)) 10:32:06 INFO - Cleaning up... 10:32:06 INFO - Return code: 0 10:32:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:32:06 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')]} 10:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:06 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')]} 10:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:06 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 0x101a9a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d35ace60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aed30>, '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 0x101714db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018a6b20>, '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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 10:32:06 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 10:32:06 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 10:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pXEUoQ5YPo/Render', 10:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:06 INFO - 'HOME': '/Users/cltbld', 10:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:06 INFO - 'LOGNAME': 'cltbld', 10:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:06 INFO - 'MOZ_NO_REMOTE': '1', 10:32:06 INFO - 'NO_EM_RESTART': '1', 10:32:06 INFO - 'PAGER': '/bin/cat', 10:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:06 INFO - 'PWD': '/builds/slave/test', 10:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:06 INFO - 'SHELL': '/bin/bash', 10:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVr3WhFkuy/Listeners', 10:32:06 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 10:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:06 INFO - 'USER': 'cltbld', 10:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:06 INFO - 'XPC_FLAGS': '0x0', 10:32:06 INFO - 'XPC_SERVICE_NAME': '0', 10:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:32:06 INFO - Cleaning up... 10:32:06 INFO - Return code: 0 10:32:06 INFO - Running post-action listener: _resource_record_post_action 10:32:06 INFO - Running post-action listener: _start_resource_monitoring 10:32:07 INFO - Starting resource monitoring. 10:32:07 INFO - [mozharness: 2016-11-14 18:32:07.026115Z] Finished create-virtualenv step (success) 10:32:07 INFO - [mozharness: 2016-11-14 18:32:07.027170Z] Running install step. 10:32:07 INFO - Running pre-action listener: _resource_record_pre_action 10:32:07 INFO - Running main action method: install 10:32:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:32:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:32:07 INFO - Reading from file tmpfile_stdout 10:32:07 INFO - Detecting whether we're running mozinstall >=1.0... 10:32:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:32:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:32:07 INFO - Reading from file tmpfile_stdout 10:32:07 INFO - Output received: 10:32:07 INFO - Usage: mozinstall [options] installer 10:32:07 INFO - Options: 10:32:07 INFO - -h, --help show this help message and exit 10:32:07 INFO - -d DEST, --destination=DEST 10:32:07 INFO - Directory to install application into. [default: 10:32:07 INFO - "/builds/slave/test"] 10:32:07 INFO - --app=APP Application being installed. [default: firefox] 10:32:07 INFO - mkdir: /builds/slave/test/build/application 10:32:07 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'] 10:32:07 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 10:32:39 INFO - Reading from file tmpfile_stdout 10:32:39 INFO - Output received: 10:32:39 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:32:39 INFO - Running post-action listener: _resource_record_post_action 10:32:39 INFO - [mozharness: 2016-11-14 18:32:39.130311Z] Finished install step (success) 10:32:39 INFO - [mozharness: 2016-11-14 18:32:39.130476Z] Running run-tests step. 10:32:39 INFO - Running pre-action listener: _resource_record_pre_action 10:32:39 INFO - Running main action method: run_tests 10:32:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:32:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:32:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:32:39 INFO - 2016-11-14 10:32:39.145 screenresolution[1641:7449] starting screenresolution argv=screenresolution get 10:32:39 INFO - 2016-11-14 10:32:39.147 screenresolution[1641:7449] Display 0: 1600x1200x32@60 10:32:39 INFO - 2016-11-14 10:32:39.153 screenresolution[1642:7451] starting screenresolution argv=screenresolution list 10:32:39 INFO - Available Modes on Display 0 10:32:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:32:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:32:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:32:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:32:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:32:39 INFO - Intel Iris: 10:32:39 INFO - Chipset Model: Intel Iris 10:32:39 INFO - Type: GPU 10:32:39 INFO - Bus: Built-In 10:32:39 INFO - VRAM (Dynamic, Max): 1536 MB 10:32:39 INFO - Vendor: Intel (0x8086) 10:32:39 INFO - Device ID: 0x0a2e 10:32:39 INFO - Revision ID: 0x0009 10:32:39 INFO - Displays: 10:32:39 INFO - Display: 10:32:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:32:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:32:39 INFO - Display Serial Number: 1600x1200 60 10:32:39 INFO - Main Display: Yes 10:32:39 INFO - Mirror: Off 10:32:39 INFO - Online: Yes 10:32:39 INFO - Rotation: Supported 10:32:39 INFO - Return code: 0 10:32:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:32:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:32:39 INFO - Python 2.7.3 10:32:39 INFO - Return code: 0 10:32:39 INFO - grabbing minidump binary from tooltool 10:32:39 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')]} 10:32:39 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 0x1018aed30>, '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 0x101714db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018a6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:32:39 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 10:32:39 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 10:32:39 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 10:32:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:32:39 INFO - Return code: 0 10:32:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:32:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:32:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:32:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:32:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:32:39 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/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0359', '--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 10:32:39 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/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0359 --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 10:32:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pXEUoQ5YPo/Render', 10:32:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:39 INFO - 'HOME': '/Users/cltbld', 10:32:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:39 INFO - 'LOGNAME': 'cltbld', 10:32:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:32:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:39 INFO - 'MOZ_NO_REMOTE': '1', 10:32:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:32:39 INFO - 'NO_EM_RESTART': '1', 10:32:39 INFO - 'PAGER': '/bin/cat', 10:32:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:39 INFO - 'PWD': '/builds/slave/test', 10:32:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:32:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:39 INFO - 'SHELL': '/bin/bash', 10:32:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BVr3WhFkuy/Listeners', 10:32:39 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 10:32:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:39 INFO - 'USER': 'cltbld', 10:32:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:39 INFO - 'XPC_FLAGS': '0x0', 10:32:39 INFO - 'XPC_SERVICE_NAME': '0', 10:32:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:39 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/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0359', '--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 10:32:39 INFO - mozversion application_buildid: 20161114074144 10:32:39 INFO - mozversion application_changeset: 8cdff71808a0e11361bca7f218658c4343c730ca 10:32:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:32:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:32:39 INFO - mozversion application_name: Firefox 10:32:39 INFO - mozversion application_remotingname: firefox-dev 10:32:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:32:39 INFO - mozversion application_vendor: Mozilla 10:32:39 INFO - mozversion application_version: 52.0a2 10:32:39 INFO - mozversion platform_buildid: 20161114074144 10:32:39 INFO - mozversion platform_changeset: 8cdff71808a0e11361bca7f218658c4343c730ca 10:32:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:32:39 INFO - mozversion platform_version: 52.0a2 10:32:39 INFO - using testdate: 1479148359 10:32:39 INFO - actual date: 1479148359 10:32:39 INFO - starting webserver on 'localhost:49270' 10:32:39 INFO - SUITE-START | Running 2 tests 10:32:39 INFO - TEST-START | dromaeo_css 10:32:39 INFO - operating with platform_type : mac_ 10:32:39 INFO - Initialising browser for dromaeo_css test... 10:32:39 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmptq7dxu/profile http://localhost:49270/getInfo.html) 10:32:41 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 10:32:42 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 10:32:42 INFO - PROCESS | 1652 | colorDepth:24 10:32:42 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 10:32:42 INFO - PROCESS | 1652 | __metrics 10:32:42 INFO - PROCESS | 1652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:32:42 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:32:43 INFO - TEST-INFO | 1652: exit 0 10:32:43 INFO - Browser initialized. 10:32:43 INFO - Running cycle 1/1 for dromaeo_css test... 10:32:43 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmptq7dxu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:32:55 INFO - PROCESS | 1654 | RSS: Main: 217227264 10:32:55 INFO - PROCESS | 1654 | 10:33:46 INFO - PROCESS | 1654 | 1479148426356 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:36:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 10:36:03 INFO - PROCESS | 1654 | RSS: Main: 306020352 10:36:03 INFO - PROCESS | 1654 | 10:39:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 10:39:06 INFO - PROCESS | 1654 | RSS: Main: 350707712 10:39:06 INFO - PROCESS | 1654 | 10:42:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 10:42:09 INFO - PROCESS | 1654 | RSS: Main: 328904704 10:42:09 INFO - PROCESS | 1654 | 10:45:11 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 10:45:12 INFO - PROCESS | 1654 | RSS: Main: 330743808 10:45:12 INFO - PROCESS | 1654 | 10:48:15 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 10:48:16 INFO - PROCESS | 1654 | RSS: Main: 313171968 10:48:16 INFO - PROCESS | 1654 | 10:51:19 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 10:51:19 INFO - PROCESS | 1654 | RSS: Main: 451231744 10:51:19 INFO - PROCESS | 1654 | 10:51:19 INFO - PROCESS | 1654 | __start_tp_report 10:51:19 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 10:51:19 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 10:51:19 INFO - PROCESS | 1654 | |i|pagename|runs| 10:51:19 INFO - PROCESS | 1654 | |0;dojo.html;3344.347852168827;3347.9497807532885;3354.798712077274;3381.8816341428055;3413.590369155702;5720.79977200798;5834.883302333951;5841.707914604274;5846.0061789495785;5858.326292476367;4798.496157903413;5817.127430885369;5847.649141051529;5852.531797456204;5864.794732184364;5247.921281180786;5250.133727934886;5267.947320526803;5307.469253074681;5317.388500322454;5216.556592689626;5219.19102539107;5220.216967454874;5355.384130824966;5381.67709937403;4598.862034138964;5212.244224587433;5297.920531192035;5327.854511280074;5335.7865685372735;5346.251524786533;5347.491988261109;5363.088274993222;5379.784808607651;5391.110466772978;5210.97394513025;5308.734563271821;5310.123829568117;5313.256144139824;5338.572914166857;5859.326177489601;5867.090600956859;5875.970620146914;5877.706114694248;5882.617629854046;6244.438000579969;6263.87472250557;6264.59280146789;6272.623642581414;6276.089966954766;6252.624842509418;6329.493640508795;6337.968310158466;6342.619442833398;6354.714037868307;4923.507649235084;5809.419058094199;5824.141379464784;5838.912416313716;5876.059830427154;6217.626942383471;6340.397662222082;6358.58669186506;6366.204224471941;6396.744130234739;4966.975165124223;6101.633901965896;6168.784092556829;6177.166082578884;6236.282827474808;6306.1171435998995;6312.274088479791;6346.8413289667205;6355.904661430082;6371.74513019474;6326.9683651581445;6327.114204011442;6343.080253363237;6356.809295721136;6361.968190159112;6153.892299386064;6198.81403557894;6205.317415084337;6217.067439883963;6261.499080073583;5012.04771093491;5012.548870601663;5029.119904016782;5029.84910452687;5044.974775126174;4492.05666809967;4507.684462087688;4509.616682582017;4512.323151527297;4545.477270113913;5779.942200578024;5807.186993820868;5823.126531020381;5882.676452795052;6026.7890623828835;6297.244330680256;6324.525660575383;6330.873382532323;6332.8416789579705;6343.492520598434;5984.431479009487;6013.819585412444;6015.368386319461;6028.065649824284;6042.909356359658;5315.601329900195;5479.726013699299;5512.91730624049;5513.807016754475;5524.613277070566;6300.621962682254;6316.842078948063;6362.968185159045;6373.649449280334;6398.296187419473;6322.083297921684;6333.809985700436;6336.619802811846;6348.333424990309;6348.650824204713;6407.935920640921;6409.359064093554;6450.354964503512;6460.773872914426;6489.448396886129;6118.694065296629;6181.5363847710705;6184.845378865564;6204.3795620437595;6303.716332764945;6261.968690156702;6288.402601752826;6312.400321969406;6322.778702745381;6351.936480635134;6205.348438414082;6212.285587157535;6249.96875015622;6265.467435268053;6267.529935254783;6100.115483254992;6133.579501509301;6163.691815409122;6256.7810126645345;6267.373262673696;5888.499477544286;5902.055671092605;5915.438033386821;5974.2233509643465;6007.158997740233;5850.64896106235;6139.079138129317;6145;6146.385361463817;6154.753809847556;4814.518548145157;5750.7987220447085;5946.5540084494705;6028.065649824284;6075 10:51:19 INFO - PROCESS | 1654 | |1;ext.html;476.2094922428769;483.7895515450779;484.00053888719793;509.2310610977424;591.2609238451935;37624.623753762455;39441.802790986076;39599.81200563984;39626.40560391589;39691.60308396915;40267.59732402675;40816.795916020375;40871.18257634845;40897.182056358855;40964.59035409645;9097.181253687151;9265.25877929764;9266.258699304039;9276.304277179204;9282.303827212952;9250.629974801;9334.813303733921;9350;9353.11145441182;9356.111169438893;9315;9369.15677589017;9369.67206147785;9370.437773733564;9372.156505914467;9252.39859409143;9264.073592640749;9314.95342523283;9328.953355233249;9345.719628411158;9202.585883635253;9257.351985361027;9290;9297.674581389687;9300.116488933609;38825.22349553021;39195.02012449712;39196.21607567833;39219.60780392188;39264.46619804203;3145.449546329406;3292.407366673998;3305.7190138838228;3321.8671253149846;3325.817080060596;28078.71921280802;29281.707182928323;30222.395552089056;31096.378072438652;31391;3010.698930106994;3123.2191952012;3190.8564114614896;3204.295055087877;3214.067920303109;440235;509592;510695;511644;521976;32824;61501.38498614957;61948.38051619516;61975.6901215491;62123.37876621266;33430.8328458361;34593.654063459544;34625.30749385049;34745;34745.652543474745;14222.075565088237;14314.284285785669;14421.495247666282;14444.566663000127;14468.710625787637;10414.68755937308;11728.472218750177;12163;12296.754064918652;12314;7644.464887457824;7651.464397492122;7667.079950405987;7687.34657554103;7724.806879828049;3140.544621029938;3175.491921292582;3181.109289398972;3183.1405520509334;3203.9839800801315;945.3052006775015;999.3154689038032;1016.2632091733434;1022.5909636145601;1026.6201505443034;28680.282992924927;29044.709552904624;29165.56251656227;29523.966661167186;29594;1050.14937900301;1062.9043386095288;1082.723905404117;1096.9067629251444;1171.6953592066125;14745.483908063381;15184.544463666107;15185.316660750294;15561.455349062955;15570.532884013497;683930;689798;690986;693573;696252;48640.51359486431;55925.322240332855;56550.43449565534;56617.43382566122;56662.86674266574;1243.030436259719;1312.4618906248393;1319.6898728798917;1371.6296599917966;1373.807666926611;28738.712612873605;29933.102006939826;29965.700342997163;30481.780728770602;30516;47645.52354476549;48184.51815481941;48206;48227.27659085187;48378;527649;528099;528413;674987;685541;25735.871320643277;26380.3404914871;26398.340041498348;26759.598606021314;26797.06210282631;44289.77855110704;44360.33459498046;44672.10655786931;45171.0965780676;45509.54490455053;30984.60569274433;37203.813980929925;37373;37622.43566346452;37795.43306850454;37411.43882841705;37847.81076094602;38009.80995095007;38012.80993595014;38547.42178867375;37101.814490927376;37551.06122346854;37704.81147594244;37895.621043790314;37911.81044094762;31861;33736.66263337335;33857.98426047222;33977;34010;24363.878180608983;24929.376765581004;25278.7360631964;25566.48867022612;25634.487310253317 10:51:19 INFO - PROCESS | 1654 | |2;jquery.html;277.3385475640597;344.2272099137437;357.1660173494889;364.04695621702643;372.68694296790693;14424.639384015392;14643.414263429464;15389.153596552185;15502.68994620107;18265.54336141597;18824.24703011878;19018.334358297463;19465;19465.124069416874;19688.60622787547;5588.888222235553;5866.853328666796;5900.940990590104;5907.995640741085;5913.201717768089;5591.916121258185;5780.421957804228;5848.795292164775;5879.735411906453;5889.911651325234;5809.477147056764;5842.883142337172;5846.093855452412;5849.912251316234;5853.560982926277;5548.722563871831;5755.625884317506;5831.30024397073;5833.066709326487;5840.328362238354;5019.723915184663;5067.9239811402485;5136.845894623168;5145.151050076733;5145.176771716508;18801.15394807237;23582;24197.395065123335;24512.387190320205;24744.010239590396;2237.69791078204;2472.8878241458024;2618.973810261892;2801.075645037153;2996.4905965985836;14717.705645887023;14908.987286716263;15068.246587670683;15329.770053449096;15459.922700386538;1749.2041121289783;1780.946571602854;1828.4788835182042;1877.5775450523522;1916.6166766646727;117644;119834;121406.99999999911;123588.38205809004;124311;10644.201684873666;10644.89355106439;11073.114150868072;11285.830712539318;11288.492017859051;13424.194548327132;13858.445662173603;13890.444382224598;13895.444182232799;13904.58286251414;7157.534760240567;8239.134890836496;8500.53247071417;8542.572871356408;8647.524386158822;6770.966145169243;7013.333733295329;8452.830943381097;8468.364872634576;8606.526641034803;5955.642661440327;6371.458426033746;6441.420272175526;6579.80260592183;6588.143541339691;1439.1073941377697;1737.3224442467447;1842.4012196036342;1848.288408962559;1849.1771161832855;725.354434553248;908.4594666173616;916.3356566489242;956.5552018311519;986.1420564109271;20238;21627.567448650938;21700.674489882666;21732.674009889863;21827;877.2543338162511;939.96710115147;947.4362754161263;951.6383774165772;963.6868017894212;9115.589798459085;10960.616378426876;11129.220954533104;11138;11299.943500282447;240709;388838;596685;620775;623022;50957;61860;67150.32124196934;67612;102938.45592316121;972.3679608254549;981.9116279534876;982.0866594067443;1004.9799004020025;1017.3438132404558;18268.615532272426;18525.722114168027;18669.34657287043;18695.813041869405;18728.7190692137;51911.74044129755;52222.21666674927;53090.93818123539;53322;54104.458955409944;54475.72762136164;56491.71754141203;66385.33614663985;69401;71945.6402717983;7589.316961473494;7701;7843.764687059397;7946.7615971520945;8058.637361318872;12300.446479908605;12323.183888755952;12598.937005314914;12646.683832904251;12698.492060317483;8517.659293628185;10976.890231097907;11019.504122314354;11111.38887361203;11311.490982905625;8458.74623761288;10731.678049658523;10772.946135269274;10883.782324353628;11037.944810275898;10843.837342439712;10887;10912.799592045027;10978.066864316303;11412.657620271073;7751.612419379121;7935.1271360149185;7998.960005199937;8120.390970677105;8153.388495862952;10553.208509361675;10731;11004.284721493077;11016.614418495314;11246.66260012201 10:51:19 INFO - PROCESS | 1654 | |3;mootools.html;1479.9778003329952;1494.1259363272486;1569.3957826236895;1658.3781082094215;1829.396299221257;8001.519908805476;8475.32197424206;8481.533515656636;8485.44844585103;8498.277646400056;5901.704914754256;7444.925550744491;7466.365358944497;8058.758237252891;8272.83454330913;2283.0867652938873;2318.431984163882;2323.1288266899915;2324.535092981402;2330.0446816805115;2326.93019209424;2339.0643742502966;2366.148186652804;2373.8694371809543;2375.714914210297;2323.384303159664;2328.9650655240184;2357.9882100589557;2360.7285162206394;2361.787439130478;2308.2728940383727;2318.246569864792;2334.136369543263;2339.9181028664084;2345.5191685704476;1910.808919108092;1941.6407964526609;2338.5673650374733;2344.120954642009;2352.2590384028977;8267.214614611603;8338.749837504885;8375.20435558621;8385.6226469809;8393.202645748644;5034.874128146789;5177.301064356301;5189.610779191576;5189.962007598496;5200.973995130038;3221.903342899717;3292.6378098409155;3415.829208539588;3518.8768393106375;3527.5061491391225;1057.4501259345097;1064.41989115932;1084.8806631270554;1095.9287646302964;1104.536094840169;10906.291091079134;10983.121350291958;11045.502952367162;11061.502232399562;11069.944650276777;4449.1101779644205;4568.223402021665;4648.442186937589;4648.861523699136;4702.200625893606;9417.717468475958;9424.858627120599;9434.622615095457;9438.48088355147;9445.527723613792;5126.4617215191865;5133.512316329943;5134.768935397841;5145.099607568735;5150.948490515047;4210.85262015828;4474.217012022883;4685.765711714469;4697.8355757548325;4736.242201247784;3783.791891445997;3798.487204227449;3864.439656249884;3899.74651647642;3910.1202229498135;1252.1985929005443;1261.218044812204;1263.8041103628952;1269.7271883328738;1275.853276873153;845.849644483502;847.2332539052168;867.8741582470507;918.2424499787702;924.1036194890945;5615.354234263031;5657.179708942181;5801.274840644919;5810.983077961339;6030.547708921848;907.5598334807613;912.2792993535033;927.7170463008712;927.958241879117;961.4952150121243;3695.2055308108884;3749.9812500937865;3754.680852127545;3796.9430458542593;3836.8081595920094;9067.909320906838;9457.574409151604;9957.651482198233;10339.586416543254;10350.53422595985;9183.760192373944;9730.659426920165;10072;10304.227182961307;10313.381197128196;7625.771226863092;7794.805129871798;7915.564643944638;7940.245676660708;7947.165547617533;5345.679259244458;5444.8366549003595;5465.9726701366235;5575.609707320611;5578.414266501959;9356.204722598655;9410.576524056298;9495.05049495045;9510.572024258787;9565.04349565038;9305.162535371848;9473.66842160521;9502.049795020443;9565.186959108554;9722.368046076983;6315.5263355247625;6524.869502610016;6526.804195874131;6755.729770809113;6899;7728.033995750531;7934.801629959297;8046.155153708776;8116.066652334821;8124.83750325002;7071.646417678992;7191.82020449472;7311.561306321638;7403.925960740539;7434.070741157356;6390.712417941297;6889.724411023503;6962.547434416747;7110.751123710852;7243.3843123333;6695.263521012587;6713.1944166700305;6761.459083273425;6775.1531058617675;6848.863022739617;4816.301636262652;4826.710397376169;4852.223644216909;4905.313256144072;4909.7545122743;4781.211100168433;4800.087983283165;4820.71075735457;4950.752462376939;4991.301217829434 10:51:19 INFO - PROCESS | 1654 | |4;prototype.html;296.1426669790955;296.1530024130985;303.38867182627;306.61060453224394;309.1174696242227;4587.830103323656;5273.762680679369;5299.443558426363;5488.368837583679;5513.393526712059;4784.162771514988;4792.424909010915;5070.923936140961;5203.583713302936;5377.865553361168;3487.581490221164;3581.1736014632593;3937.6259255370837;4069.270560011983;4117.649999750025;3630.80030598317;3776.622337766229;4044.170944956277;4065.8451020958937;4075.839804903406;3928.5874983126787;3996.7002474814503;4061.390791381287;4067.450894129299;4096.918061638766;4067.491563554556;4071.902969970453;4074.4703188585295;4077.9805048737817;4109.732867363627;3529.6117427083;3540.150363912642;3543.698785603227;3548.6628770266784;3555.928881422357;4153.231652144363;4252.489701235841;5176.715280659562;5181.5077567631015;5250.298539529661;3913.021744563859;4106.835726570934;4565.223911934979;4625.444946606429;4678.695884767513;5176.378834539879;5632.408597097327;5656.585853536616;5671.262735844355;5683.5737319700775;3268.921255985519;3301.6203136639356;3632.6398081774255;3917.7061720370803;3936.704747143947;4606.930896036562;4975.616580725105;5018.648694591344;5072.391313042384;5711.314642242916;3099.9225019374244;3803.220339830328;3825.6174382562076;3844.75009124406;3850.728070438455;3895.240428116547;3903.7748483364776;3906.2539015096795;3917.9020524486546;3925.193486657054;3901.49280593521;4363.36731173978;4573.947991961801;4581.656375771764;4605;5549.364052075312;5564.415736347625;5617.578681598897;5626.2967129108865;5639.379668236573;1094.4035500652005;1197.5150064224003;1251.787196176652;1256.761215369077;1283.0633637444719;685.9382655560923;805.8630032894422;826.3017750001239;834.1533343656193;862.9654813807377;3867.400552914303;4572.565606267399;4862;4864.419063804207;4964.400809413147;5080.923786143211;5328.866778330495;5433.098415554524;5490.69801160932;5553.583481238923;3993.5008123984503;4645.279981602857;4659.743714095757;4706.7411292378565;4921.87695307613;2455.901763929423;2663.4539919316494;2709.279607449284;2845.1506520761673;2862.298736809495;4807.7980504873785;5083.491650834961;5371.696406709845;5539.473749993744;5565.972170139124;4861.489543597942;5503.807366742145;5551.3893471718075;5888.941110588925;5959.523238140939;5508.44915508446;5622.71071423222;5914.0241860093465;5996.310424301261;6056.757729690763;5581.330240371181;5769.1634712967225;5831.35855055935;5876.236089308362;5912.231409916855;4843.837479004943;5262.868428289324;5412.404635490172;5776.508996735324;5843.912341314799;5476.561875049907;5787.131930210334;5979.402059793986;6079.726412311368;6085.361037091042;3708.295423869456;3749.1939233065023;3835.1782903120793;3845.7307988441657;3870.651641352292;5827.562932779973;5834.679092649945;5840.269966254218;5843.444872737083;5873.324567674772;5611.410801865909;5611.663300202001;5646.604737668324;5726.828195154152;5754.280714910637;4709.646776491845;4987.750612469289;5409.61820925052;5458.1266997280245;5551.833444996657;5021.874453138555;5231.6397736588;5416.83749487516;5525.8948210357285;5568.415316391721;5121.385433747974;5505.009098362173;5545.504522964763;5561.524047470331;5582.655284780483;4595.609373203315;5458.0721277382145;5545.944540554543;5552.916706249329;5556.6662001960085 10:51:19 INFO - PROCESS | 1654 | |5;yui.html;593.2948735148995;728.6101935464526;764.121260550367;770.0566805663067;775.9495632783866;13373.331333433325;14070.507532236374;14267.785983210248;14279.714405711893;14739.48411805587;12800.487980480793;13070;13074.477020919176;13075.476980920776;13084.672883177926;644.80011196529;664.7939138866967;802.3701394405407;818.1491249100928;821.3388222480901;628.5600079944057;804.8189157439588;822.1244374740912;835.340081335744;836.5942517878824;872.3239489395705;873.7597160780805;875.1642181716445;875.1642181716477;878.2622597018503;675.8850995330805;868.6699054359366;871.333429926107;872.2193636695154;876.5310558850985;512.861527387607;518.1528201390715;518.9584833213333;519.8336532309662;627.4635186915214;13392.732145357038;13885.583432497042;13887.4445022199;13900.165990040528;13912.37394317258;12606.243625382507;12761.361931903368;12832.294223817686;12846.678833029155;13172.275524846129;10473.47632618366;10795.784084318348;10940.12479001678;12007.279563226235;12372.257664540157;4384.232759267115;4401.955980440187;4432.645388368923;4518.593326600589;4561.612262957653;83651.58174209151;85202;85338;89009.55495222482;89129.55435222847;9831.360961537477;9950.402975821475;10406.356729629804;10454.372737635766;10466.52900619474;19062.80937190638;19065.42803715891;19086.904565477085;19092.618147637248;19146.617067658848;7234.385077268385;7257.201707812136;7259.89110163348;7367.963160184165;7615.885761713579;4126.5350800466;4178.143480586473;4229.61933425987;4310.741355518679;4373.759443230653;3046.558249053875;3094.133642580081;3111.626604807392;3132.3578666373337;3132.7493800495904;616.315889362807;628.7516431009725;630.8012975912573;639.1722719078804;647.7369130196137;519.2704250528014;615.2770494668746;655.3773914780882;665.9884370114924;679.3512195852949;8859.601317940707;8953.283737301;9012.909870901207;9176.72469825893;9353.345265831462;632.5287660692721;646.8253571535658;653.9934600654028;670.6780745242311;676.0670275020539;4213.662906967496;4499.505054444008;4525.864224073283;4552.70407423523;4569.177548041318;68712;99717.50141249248;104313.47843260736;108879;118732;89496;91737;92431;98885.01114988902;99033;85946.71079933806;86409;89688.5515572418;89855.65216521757;91051;8630.78423039429;8770.079141690287;8852.424592401474;8940.04202498537;9002.639894404412;48247.5175248243;48647.27029094637;51067.744661276454;57365;57455.850882982944;65122;69253.65373173101;69647.65176174087;70691;75534;11965.162438629213;11985;12072.577459788865;12363.443645035815;12549.498020079094;13106.410211540313;13340;14015.089019213718;14205.005649604842;14774.409023639364;12664.936675316565;13139.73720525565;13304;13378.888689084557;13389.531366402129;13125.54060607874;13740.244286563935;13942.930285348508;14148.292585370897;14157.716845663235;12284.631461056182;12818.871811281768;13501.797473037715;13692.973027022814;13800.964927630668;9168.816623667355;10181.592736290466;10268.897311026794;10595.94702026485;10676.83984740245;9374.484403357881;9592.808143837225;10293.3823970562;11158.107351411705;11458.369789661649 10:51:19 INFO - PROCESS | 1654 | __end_tp_report 10:51:19 INFO - PROCESS | 1654 | __start_cc_report 10:51:19 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,2022 10:51:19 INFO - PROCESS | 1654 | __end_cc_report 10:51:19 INFO - PROCESS | 1654 | __startTimestamp1479149479677__endTimestamp 10:51:19 INFO - PROCESS | 1654 | 10:51:19 INFO - PROCESS | 1654 | ------- Summary: start ------- 10:51:19 INFO - PROCESS | 1654 | Number of tests: 6 10:51:19 INFO - PROCESS | 1654 | 10:51:19 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5802.83 Median:6026.79 stddev:662.14 (11.0%) stddev-sans-first:635.46 10:51:19 INFO - PROCESS | 1654 | Values: 3344.3 3347.9 3354.8 3381.9 3413.6 5720.8 5834.9 5841.7 5846.0 5858.3 4798.5 5817.1 5847.6 5852.5 5864.8 5247.9 5250.1 5267.9 5307.5 5317.4 5216.6 5219.2 5220.2 5355.4 5381.7 4598.9 5212.2 5297.9 5327.9 5335.8 5346.3 5347.5 5363.1 5379.8 5391.1 5211.0 5308.7 5310.1 5313.3 5338.6 5859.3 5867.1 5876.0 5877.7 5882.6 6244.4 6263.9 6264.6 6272.6 6276.1 6252.6 6329.5 6338.0 6342.6 6354.7 4923.5 5809.4 5824.1 5838.9 5876.1 6217.6 6340.4 6358.6 6366.2 6396.7 4967.0 6101.6 6168.8 6177.2 6236.3 6306.1 6312.3 6346.8 6355.9 6371.7 6327.0 6327.1 6343.1 6356.8 6362.0 6153.9 6198.8 6205.3 6217.1 6261.5 5012.0 5012.5 5029.1 5029.8 5045.0 4492.1 4507.7 4509.6 4512.3 4545.5 5779.9 5807.2 5823.1 5882.7 6026.8 6297.2 6324.5 6330.9 6332.8 6343.5 5984.4 6013.8 6015.4 6028.1 6042.9 5315.6 5479.7 5512.9 5513.8 5524.6 6300.6 6316.8 6363.0 6373.6 6398.3 6322.1 6333.8 6336.6 6348.3 6348.7 6407.9 6409.4 6450.4 6460.8 6489.4 6118.7 6181.5 6184.8 6204.4 6303.7 6262.0 6288.4 6312.4 6322.8 6351.9 6205.3 6212.3 6250.0 6265.5 6267.5 6100.1 6133.6 6163.7 6256.8 6267.4 5888.5 5902.1 5915.4 5974.2 6007.2 5850.6 6139.1 6145.0 6146.4 6154.8 4814.5 5750.8 5946.6 6028.1 6075.0 10:51:19 INFO - PROCESS | 1654 | 10:51:19 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:70310.84 Median:37903.72 stddev:161041.70 (424.9%) stddev-sans-first:161408.11 10:51:19 INFO - PROCESS | 1654 | Values: 476.2 483.8 484.0 509.2 591.3 37624.6 39441.8 39599.8 39626.4 39691.6 40267.6 40816.8 40871.2 40897.2 40964.6 9097.2 9265.3 9266.3 9276.3 9282.3 9250.6 9334.8 9350.0 9353.1 9356.1 9315.0 9369.2 9369.7 9370.4 9372.2 9252.4 9264.1 9315.0 9329.0 9345.7 9202.6 9257.4 9290.0 9297.7 9300.1 38825.2 39195.0 39196.2 39219.6 39264.5 3145.4 3292.4 3305.7 3321.9 3325.8 28078.7 29281.7 30222.4 31096.4 31391.0 3010.7 3123.2 3190.9 3204.3 3214.1 440235.0 509592.0 510695.0 511644.0 521976.0 32824.0 61501.4 61948.4 61975.7 62123.4 33430.8 34593.7 34625.3 34745.0 34745.7 14222.1 14314.3 14421.5 14444.6 14468.7 10414.7 11728.5 12163.0 12296.8 12314.0 7644.5 7651.5 7667.1 7687.3 7724.8 3140.5 3175.5 3181.1 3183.1 3204.0 945.3 999.3 1016.3 1022.6 1026.6 28680.3 29044.7 29165.6 29524.0 29594.0 1050.1 1062.9 1082.7 1096.9 1171.7 14745.5 15184.5 15185.3 15561.5 15570.5 683930.0 689798.0 690986.0 693573.0 696252.0 48640.5 55925.3 56550.4 56617.4 56662.9 1243.0 1312.5 1319.7 1371.6 1373.8 28738.7 29933.1 29965.7 30481.8 30516.0 47645.5 48184.5 48206.0 48227.3 48378.0 527649.0 528099.0 528413.0 674987.0 685541.0 25735.9 26380.3 26398.3 26759.6 26797.1 44289.8 44360.3 44672.1 45171.1 45509.5 30984.6 37203.8 37373.0 37622.4 37795.4 37411.4 37847.8 38009.8 38012.8 38547.4 37101.8 37551.1 37704.8 37895.6 37911.8 31861.0 33736.7 33858.0 33977.0 34010.0 24363.9 24929.4 25278.7 25566.5 25634.5 10:51:19 INFO - PROCESS | 1654 | 10:51:19 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:30062.25 Median:2898.78 stddev:86163.17 (2972.4%) stddev-sans-first:86375.86 10:51:19 INFO - PROCESS | 1654 | Values: 277.3 344.2 357.2 364.0 372.7 14424.6 14643.4 15389.2 15502.7 18265.5 18824.2 19018.3 19465.0 19465.1 19688.6 5588.9 5866.9 5900.9 5908.0 5913.2 5591.9 5780.4 5848.8 5879.7 5889.9 5809.5 5842.9 5846.1 5849.9 5853.6 5548.7 5755.6 5831.3 5833.1 5840.3 5019.7 5067.9 5136.8 5145.2 5145.2 18801.2 23582.0 24197.4 24512.4 24744.0 2237.7 2472.9 2619.0 2801.1 2996.5 14717.7 14909.0 15068.2 15329.8 15459.9 1749.2 1780.9 1828.5 1877.6 1916.6 117644.0 119834.0 121407.0 123588.4 124311.0 10644.2 10644.9 11073.1 11285.8 11288.5 13424.2 13858.4 13890.4 13895.4 13904.6 7157.5 8239.1 8500.5 8542.6 8647.5 6771.0 7013.3 8452.8 8468.4 8606.5 5955.6 6371.5 6441.4 6579.8 6588.1 1439.1 1737.3 1842.4 1848.3 1849.2 725.4 908.5 916.3 956.6 986.1 20238.0 21627.6 21700.7 21732.7 21827.0 877.3 940.0 947.4 951.6 963.7 9115.6 10960.6 11129.2 11138.0 11299.9 240709.0 388838.0 596685.0 620775.0 623022.0 50957.0 61860.0 67150.3 67612.0 102938.5 972.4 981.9 982.1 1005.0 1017.3 18268.6 18525.7 18669.3 18695.8 18728.7 51911.7 52222.2 53090.9 53322.0 54104.5 54475.7 56491.7 66385.3 69401.0 71945.6 7589.3 7701.0 7843.8 7946.8 8058.6 12300.4 12323.2 12598.9 12646.7 12698.5 8517.7 10976.9 11019.5 11111.4 11311.5 8458.7 10731.7 10772.9 10883.8 11037.9 10843.8 10887.0 10912.8 10978.1 11412.7 7751.6 7935.1 7999.0 8120.4 8153.4 10553.2 10731.0 11004.3 11016.6 11246.7 10:51:19 INFO - PROCESS | 1654 | 10:51:19 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5430.77 Median:5164.12 stddev:2971.96 (57.6%) stddev-sans-first:2965.47 10:51:19 INFO - PROCESS | 1654 | Values: 1480.0 1494.1 1569.4 1658.4 1829.4 8001.5 8475.3 8481.5 8485.4 8498.3 5901.7 7444.9 7466.4 8058.8 8272.8 2283.1 2318.4 2323.1 2324.5 2330.0 2326.9 2339.1 2366.1 2373.9 2375.7 2323.4 2329.0 2358.0 2360.7 2361.8 2308.3 2318.2 2334.1 2339.9 2345.5 1910.8 1941.6 2338.6 2344.1 2352.3 8267.2 8338.7 8375.2 8385.6 8393.2 5034.9 5177.3 5189.6 5190.0 5201.0 3221.9 3292.6 3415.8 3518.9 3527.5 1057.5 1064.4 1084.9 1095.9 1104.5 10906.3 10983.1 11045.5 11061.5 11069.9 4449.1 4568.2 4648.4 4648.9 4702.2 9417.7 9424.9 9434.6 9438.5 9445.5 5126.5 5133.5 5134.8 5145.1 5150.9 4210.9 4474.2 4685.8 4697.8 4736.2 3783.8 3798.5 3864.4 3899.7 3910.1 1252.2 1261.2 1263.8 1269.7 1275.9 845.8 847.2 867.9 918.2 924.1 5615.4 5657.2 5801.3 5811.0 6030.5 907.6 912.3 927.7 928.0 961.5 3695.2 3750.0 3754.7 3796.9 3836.8 9067.9 9457.6 9957.7 10339.6 10350.5 9183.8 9730.7 10072.0 10304.2 10313.4 7625.8 7794.8 7915.6 7940.2 7947.2 5345.7 5444.8 5466.0 5575.6 5578.4 9356.2 9410.6 9495.1 9510.6 9565.0 9305.2 9473.7 9502.0 9565.2 9722.4 6315.5 6524.9 6526.8 6755.7 6899.0 7728.0 7934.8 8046.2 8116.1 8124.8 7071.6 7191.8 7311.6 7403.9 7434.1 6390.7 6889.7 6962.5 7110.8 7243.4 6695.3 6713.2 6761.5 6775.2 6848.9 4816.3 4826.7 4852.2 4905.3 4909.8 4781.2 4800.1 4820.7 4950.8 4991.3 10:51:19 INFO - PROCESS | 1654 | 10:51:19 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4466.32 Median:4970.01 stddev:1399.56 (28.2%) stddev-sans-first:1368.03 10:51:19 INFO - PROCESS | 1654 | Values: 296.1 296.2 303.4 306.6 309.1 4587.8 5273.8 5299.4 5488.4 5513.4 4784.2 4792.4 5070.9 5203.6 5377.9 3487.6 3581.2 3937.6 4069.3 4117.6 3630.8 3776.6 4044.2 4065.8 4075.8 3928.6 3996.7 4061.4 4067.5 4096.9 4067.5 4071.9 4074.5 4078.0 4109.7 3529.6 3540.2 3543.7 3548.7 3555.9 4153.2 4252.5 5176.7 5181.5 5250.3 3913.0 4106.8 4565.2 4625.4 4678.7 5176.4 5632.4 5656.6 5671.3 5683.6 3268.9 3301.6 3632.6 3917.7 3936.7 4606.9 4975.6 5018.6 5072.4 5711.3 3099.9 3803.2 3825.6 3844.8 3850.7 3895.2 3903.8 3906.3 3917.9 3925.2 3901.5 4363.4 4573.9 4581.7 4605.0 5549.4 5564.4 5617.6 5626.3 5639.4 1094.4 1197.5 1251.8 1256.8 1283.1 685.9 805.9 826.3 834.2 863.0 3867.4 4572.6 4862.0 4864.4 4964.4 5080.9 5328.9 5433.1 5490.7 5553.6 3993.5 4645.3 4659.7 4706.7 4921.9 2455.9 2663.5 2709.3 2845.2 2862.3 4807.8 5083.5 5371.7 5539.5 5566.0 4861.5 5503.8 5551.4 5888.9 5959.5 5508.4 5622.7 5914.0 5996.3 6056.8 5581.3 5769.2 5831.4 5876.2 5912.2 4843.8 5262.9 5412.4 5776.5 5843.9 5476.6 5787.1 5979.4 6079.7 6085.4 3708.3 3749.2 3835.2 3845.7 3870.7 5827.6 5834.7 5840.3 5843.4 5873.3 5611.4 5611.7 5646.6 5726.8 5754.3 4709.6 4987.8 5409.6 5458.1 5551.8 5021.9 5231.6 5416.8 5525.9 5568.4 5121.4 5505.0 5545.5 5561.5 5582.7 4595.6 5458.1 5545.9 5552.9 5556.7 10:51:19 INFO - PROCESS | 1654 | 10:51:19 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:20036.00 Median:25790.30 stddev:29184.83 (113.2%) stddev-sans-first:29230.19 10:51:19 INFO - PROCESS | 1654 | Values: 593.3 728.6 764.1 770.1 775.9 13373.3 14070.5 14267.8 14279.7 14739.5 12800.5 13070.0 13074.5 13075.5 13084.7 644.8 664.8 802.4 818.1 821.3 628.6 804.8 822.1 835.3 836.6 872.3 873.8 875.2 875.2 878.3 675.9 868.7 871.3 872.2 876.5 512.9 518.2 519.0 519.8 627.5 13392.7 13885.6 13887.4 13900.2 13912.4 12606.2 12761.4 12832.3 12846.7 13172.3 10473.5 10795.8 10940.1 12007.3 12372.3 4384.2 4402.0 4432.6 4518.6 4561.6 83651.6 85202.0 85338.0 89009.6 89129.6 9831.4 9950.4 10406.4 10454.4 10466.5 19062.8 19065.4 19086.9 19092.6 19146.6 7234.4 7257.2 7259.9 7368.0 7615.9 4126.5 4178.1 4229.6 4310.7 4373.8 3046.6 3094.1 3111.6 3132.4 3132.7 616.3 628.8 630.8 639.2 647.7 519.3 615.3 655.4 666.0 679.4 8859.6 8953.3 9012.9 9176.7 9353.3 632.5 646.8 654.0 670.7 676.1 4213.7 4499.5 4525.9 4552.7 4569.2 68712.0 99717.5 104313.5 108879.0 118732.0 89496.0 91737.0 92431.0 98885.0 99033.0 85946.7 86409.0 89688.6 89855.7 91051.0 8630.8 8770.1 8852.4 8940.0 9002.6 48247.5 48647.3 51067.7 57365.0 57455.9 65122.0 69253.7 69647.7 70691.0 75534.0 11965.2 11985.0 12072.6 12363.4 12549.5 13106.4 13340.0 14015.1 14205.0 14774.4 12664.9 13139.7 13304.0 13378.9 13389.5 13125.5 13740.2 13942.9 14148.3 14157.7 12284.6 12818.9 13501.8 13693.0 13801.0 9168.8 10181.6 10268.9 10595.9 10676.8 9374.5 9592.8 10293.4 11158.1 11458.4 10:51:19 INFO - PROCESS | 1654 | -------- Summary: end -------- 10:51:19 INFO - PROCESS | 1654 | 10:51:20 INFO - TEST-INFO | 1654: exit 0 10:51:20 INFO - TEST-OK | dromaeo_css | took 1120692ms 10:51:20 INFO - TEST-START | kraken 10:51:20 INFO - operating with platform_type : mac_ 10:51:20 INFO - Initialising browser for kraken test... 10:51:20 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpPz7HKq/profile http://localhost:49270/getInfo.html) 10:51:22 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 10:51:22 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 10:51:22 INFO - PROCESS | 1663 | colorDepth:24 10:51:22 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 10:51:22 INFO - PROCESS | 1663 | __metrics 10:51:22 INFO - PROCESS | 1663 | 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" 10:51:22 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:51:23 INFO - TEST-INFO | 1663: exit 0 10:51:23 INFO - Browser initialized. 10:51:23 INFO - Running cycle 1/1 for kraken test... 10:51:23 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpPz7HKq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:51:35 INFO - PROCESS | 1664 | RSS: Main: 217501696 10:51:35 INFO - PROCESS | 1664 | 10:51:38 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 10:51:39 INFO - PROCESS | 1664 | RSS: Main: 268230656 10:51:39 INFO - PROCESS | 1664 | 10:51:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 10:51:42 INFO - PROCESS | 1664 | RSS: Main: 277934080 10:51:42 INFO - PROCESS | 1664 | 10:51:45 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 10:51:46 INFO - PROCESS | 1664 | RSS: Main: 374845440 10:51:46 INFO - PROCESS | 1664 | 10:51:48 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 10:51:48 INFO - PROCESS | 1664 | RSS: Main: 277737472 10:51:48 INFO - PROCESS | 1664 | 10:51:50 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:51:51 INFO - PROCESS | 1664 | RSS: Main: 278872064 10:51:51 INFO - PROCESS | 1664 | 10:51:54 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 10:51:54 INFO - PROCESS | 1664 | RSS: Main: 319782912 10:51:54 INFO - PROCESS | 1664 | 10:51:57 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 10:51:58 INFO - PROCESS | 1664 | RSS: Main: 319778816 10:51:58 INFO - PROCESS | 1664 | 10:52:01 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 10:52:01 INFO - PROCESS | 1664 | RSS: Main: 318910464 10:52:01 INFO - PROCESS | 1664 | 10:52:03 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:52:04 INFO - PROCESS | 1664 | RSS: Main: 295747584 10:52:04 INFO - PROCESS | 1664 | 10:52:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:52:06 INFO - PROCESS | 1664 | RSS: Main: 296693760 10:52:06 INFO - PROCESS | 1664 | 10:52:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:52:09 INFO - PROCESS | 1664 | RSS: Main: 298926080 10:52:09 INFO - PROCESS | 1664 | 10:52:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:52:13 INFO - PROCESS | 1664 | RSS: Main: 308322304 10:52:13 INFO - PROCESS | 1664 | 10:52:16 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:52:16 INFO - PROCESS | 1664 | RSS: Main: 308441088 10:52:16 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 10:52:18 INFO - PROCESS | 1664 | RSS: Main: 351838208 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | __start_tp_report 10:52:18 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 10:52:18 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 10:52:18 INFO - PROCESS | 1664 | |i|pagename|runs| 10:52:18 INFO - PROCESS | 1664 | |0;ai-astar;116;114;114;113;116;152;113;113;113;113 10:52:18 INFO - PROCESS | 1664 | |1;audio-beat-detection;144;112;111;143;117;126;111;109;138;115 10:52:18 INFO - PROCESS | 1664 | |2;audio-dft;183;182;183;186;184;182;183;181;185;185 10:52:18 INFO - PROCESS | 1664 | |3;audio-fft;83;58;60;108;84;82;61;58;82;59 10:52:18 INFO - PROCESS | 1664 | |4;audio-oscillator;88;71;95;69;69;68;90;68;68;92 10:52:18 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;89;84;89;86;88;86;90;86;89;85 10:52:18 INFO - PROCESS | 1664 | |6;imaging-darkroom;102;95;93;94;94;94;94;94;92;93 10:52:18 INFO - PROCESS | 1664 | |7;imaging-desaturate;81;83;84;79;82;81;82;82;82;80 10:52:18 INFO - PROCESS | 1664 | |8;json-parse-financial;56;52;54;53;54;55;54;58;81;89 10:52:18 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;67;77;82;73;72;72;72;73;72;73 10:52:18 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;97;103;102;102;83;84;83;85;90;103 10:52:18 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;146;147;146;146;141;149;150;140;214;175 10:52:18 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;167;168;166;167;169;167;167;166;165;168 10:52:18 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;67;68;67;68;77;88;85;87;71;67 10:52:18 INFO - PROCESS | 1664 | __end_tp_report 10:52:18 INFO - PROCESS | 1664 | __start_cc_report 10:52:18 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2005 10:52:18 INFO - PROCESS | 1664 | __end_cc_report 10:52:18 INFO - PROCESS | 1664 | __startTimestamp1479149538873__endTimestamp 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | ------- Summary: start ------- 10:52:18 INFO - PROCESS | 1664 | Number of tests: 14 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:117.70 Median:114.00 stddev:12.11 (10.6%) stddev-sans-first:12.83 10:52:18 INFO - PROCESS | 1664 | Values: 116.0 114.0 114.0 113.0 116.0 152.0 113.0 113.0 113.0 113.0 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:122.60 Median:121.50 stddev:14.06 (11.6%) stddev-sans-first:12.60 10:52:18 INFO - PROCESS | 1664 | Values: 144.0 112.0 111.0 143.0 117.0 126.0 111.0 109.0 138.0 115.0 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:183.40 Median:183.50 stddev:1.58 (0.9%) stddev-sans-first:1.67 10:52:18 INFO - PROCESS | 1664 | Values: 183.0 182.0 183.0 186.0 184.0 182.0 183.0 181.0 185.0 185.0 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:73.50 Median:71.50 stddev:16.88 (23.6%) stddev-sans-first:17.55 10:52:18 INFO - PROCESS | 1664 | Values: 83.0 58.0 60.0 108.0 84.0 82.0 61.0 58.0 82.0 59.0 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.50 stddev:11.74 (14.8%) stddev-sans-first:11.85 10:52:18 INFO - PROCESS | 1664 | Values: 88.0 71.0 95.0 69.0 69.0 68.0 90.0 68.0 68.0 92.0 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:88.50 stddev:2.04 (2.3%) stddev-sans-first:2.06 10:52:18 INFO - PROCESS | 1664 | Values: 89.0 84.0 89.0 86.0 88.0 86.0 90.0 86.0 89.0 85.0 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.00 stddev:2.76 (2.9%) stddev-sans-first:0.87 10:52:18 INFO - PROCESS | 1664 | Values: 102.0 95.0 93.0 94.0 94.0 94.0 94.0 94.0 92.0 93.0 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:81.60 Median:82.00 stddev:1.43 (1.7%) stddev-sans-first:1.50 10:52:18 INFO - PROCESS | 1664 | Values: 81.0 83.0 84.0 79.0 82.0 81.0 82.0 82.0 82.0 80.0 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:60.60 Median:55.50 stddev:13.10 (23.6%) stddev-sans-first:13.79 10:52:18 INFO - PROCESS | 1664 | Values: 56.0 52.0 54.0 53.0 54.0 55.0 54.0 58.0 81.0 89.0 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:73.00 stddev:3.89 (5.3%) stddev-sans-first:3.39 10:52:18 INFO - PROCESS | 1664 | Values: 67.0 77.0 82.0 73.0 72.0 72.0 72.0 73.0 72.0 73.0 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:93.20 Median:83.50 stddev:9.02 (10.8%) stddev-sans-first:9.46 10:52:18 INFO - PROCESS | 1664 | Values: 97.0 103.0 102.0 102.0 83.0 84.0 83.0 85.0 90.0 103.0 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:155.40 Median:148.00 stddev:22.78 (15.4%) stddev-sans-first:23.90 10:52:18 INFO - PROCESS | 1664 | Values: 146.0 147.0 146.0 146.0 141.0 149.0 150.0 140.0 214.0 175.0 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:167.00 stddev:1.15 (0.7%) stddev-sans-first:1.22 10:52:18 INFO - PROCESS | 1664 | Values: 167.0 168.0 166.0 167.0 169.0 167.0 167.0 166.0 165.0 168.0 10:52:18 INFO - PROCESS | 1664 | 10:52:18 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.50 Median:74.00 stddev:8.95 (12.1%) stddev-sans-first:9.07 10:52:18 INFO - PROCESS | 1664 | Values: 67.0 68.0 67.0 68.0 77.0 88.0 85.0 87.0 71.0 67.0 10:52:18 INFO - PROCESS | 1664 | -------- Summary: end -------- 10:52:18 INFO - PROCESS | 1664 | 10:52:19 INFO - TEST-INFO | 1664: exit 0 10:52:19 INFO - TEST-OK | kraken | took 59341ms 10:52:19 INFO - SUITE-END | took 1180s 10:52:20 INFO - Completed test suite (00:19:40) 10:52:20 INFO - javascript benchmark 10:52:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3344.347852168827, 3347.9497807532885, 3354.798712077274, 3381.8816341428055, 3413.590369155702, 5720.79977200798, 5834.883302333951, 5841.707914604274, 5846.0061789495785, 5858.326292476367, 4798.496157903413, 5817.127430885369, 5847.649141051529, 5852.531797456204, 5864.794732184364, 5247.921281180786, 5250.133727934886, 5267.947320526803, 5307.469253074681, 5317.388500322454, 5216.556592689626, 5219.19102539107, 5220.216967454874, 5355.384130824966, 5381.67709937403, 4598.862034138964, 5212.244224587433, 5297.920531192035, 5327.854511280074, 5335.7865685372735, 5346.251524786533, 5347.491988261109, 5363.088274993222, 5379.784808607651, 5391.110466772978, 5210.97394513025, 5308.734563271821, 5310.123829568117, 5313.256144139824, 5338.572914166857, 5859.326177489601, 5867.090600956859, 5875.970620146914, 5877.706114694248, 5882.617629854046, 6244.438000579969, 6263.87472250557, 6264.59280146789, 6272.623642581414, 6276.089966954766, 6252.624842509418, 6329.493640508795, 6337.968310158466, 6342.619442833398, 6354.714037868307, 4923.507649235084, 5809.419058094199, 5824.141379464784, 5838.912416313716, 5876.059830427154, 6217.626942383471, 6340.397662222082, 6358.58669186506, 6366.204224471941, 6396.744130234739, 4966.975165124223, 6101.633901965896, 6168.784092556829, 6177.166082578884, 6236.282827474808, 6306.1171435998995, 6312.274088479791, 6346.8413289667205, 6355.904661430082, 6371.74513019474, 6326.9683651581445, 6327.114204011442, 6343.080253363237, 6356.809295721136, 6361.968190159112, 6153.892299386064, 6198.81403557894, 6205.317415084337, 6217.067439883963, 6261.499080073583, 5012.04771093491, 5012.548870601663, 5029.119904016782, 5029.84910452687, 5044.974775126174, 4492.05666809967, 4507.684462087688, 4509.616682582017, 4512.323151527297, 4545.477270113913, 5779.942200578024, 5807.186993820868, 5823.126531020381, 5882.676452795052, 6026.7890623828835, 6297.244330680256, 6324.525660575383, 6330.873382532323, 6332.8416789579705, 6343.492520598434, 5984.431479009487, 6013.819585412444, 6015.368386319461, 6028.065649824284, 6042.909356359658, 5315.601329900195, 5479.726013699299, 5512.91730624049, 5513.807016754475, 5524.613277070566, 6300.621962682254, 6316.842078948063, 6362.968185159045, 6373.649449280334, 6398.296187419473, 6322.083297921684, 6333.809985700436, 6336.619802811846, 6348.333424990309, 6348.650824204713, 6407.935920640921, 6409.359064093554, 6450.354964503512, 6460.773872914426, 6489.448396886129, 6118.694065296629, 6181.5363847710705, 6184.845378865564, 6204.3795620437595, 6303.716332764945, 6261.968690156702, 6288.402601752826, 6312.400321969406, 6322.778702745381, 6351.936480635134, 6205.348438414082, 6212.285587157535, 6249.96875015622, 6265.467435268053, 6267.529935254783, 6100.115483254992, 6133.579501509301, 6163.691815409122, 6256.7810126645345, 6267.373262673696, 5888.499477544286, 5902.055671092605, 5915.438033386821, 5974.2233509643465, 6007.158997740233, 5850.64896106235, 6139.079138129317, 6145.0, 6146.385361463817, 6154.753809847556, 4814.518548145157, 5750.7987220447085, 5946.5540084494705, 6028.065649824284, 6075.0], "value": 5760.733830873083, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.2094922428769, 483.7895515450779, 484.00053888719793, 509.2310610977424, 591.2609238451935, 37624.623753762455, 39441.802790986076, 39599.81200563984, 39626.40560391589, 39691.60308396915, 40267.59732402675, 40816.795916020375, 40871.18257634845, 40897.182056358855, 40964.59035409645, 9097.181253687151, 9265.25877929764, 9266.258699304039, 9276.304277179204, 9282.303827212952, 9250.629974801, 9334.813303733921, 9350.0, 9353.11145441182, 9356.111169438893, 9315.0, 9369.15677589017, 9369.67206147785, 9370.437773733564, 9372.156505914467, 9252.39859409143, 9264.073592640749, 9314.95342523283, 9328.953355233249, 9345.719628411158, 9202.585883635253, 9257.351985361027, 9290.0, 9297.674581389687, 9300.116488933609, 38825.22349553021, 39195.02012449712, 39196.21607567833, 39219.60780392188, 39264.46619804203, 3145.449546329406, 3292.407366673998, 3305.7190138838228, 3321.8671253149846, 3325.817080060596, 28078.71921280802, 29281.707182928323, 30222.395552089056, 31096.378072438652, 31391.0, 3010.698930106994, 3123.2191952012, 3190.8564114614896, 3204.295055087877, 3214.067920303109, 440235.0, 509592.0, 510695.0, 511644.0, 521976.0, 32824.0, 61501.38498614957, 61948.38051619516, 61975.6901215491, 62123.37876621266, 33430.8328458361, 34593.654063459544, 34625.30749385049, 34745.0, 34745.652543474745, 14222.075565088237, 14314.284285785669, 14421.495247666282, 14444.566663000127, 14468.710625787637, 10414.68755937308, 11728.472218750177, 12163.0, 12296.754064918652, 12314.0, 7644.464887457824, 7651.464397492122, 7667.079950405987, 7687.34657554103, 7724.806879828049, 3140.544621029938, 3175.491921292582, 3181.109289398972, 3183.1405520509334, 3203.9839800801315, 945.3052006775015, 999.3154689038032, 1016.2632091733434, 1022.5909636145601, 1026.6201505443034, 28680.282992924927, 29044.709552904624, 29165.56251656227, 29523.966661167186, 29594.0, 1050.14937900301, 1062.9043386095288, 1082.723905404117, 1096.9067629251444, 1171.6953592066125, 14745.483908063381, 15184.544463666107, 15185.316660750294, 15561.455349062955, 15570.532884013497, 683930.0, 689798.0, 690986.0, 693573.0, 696252.0, 48640.51359486431, 55925.322240332855, 56550.43449565534, 56617.43382566122, 56662.86674266574, 1243.030436259719, 1312.4618906248393, 1319.6898728798917, 1371.6296599917966, 1373.807666926611, 28738.712612873605, 29933.102006939826, 29965.700342997163, 30481.780728770602, 30516.0, 47645.52354476549, 48184.51815481941, 48206.0, 48227.27659085187, 48378.0, 527649.0, 528099.0, 528413.0, 674987.0, 685541.0, 25735.871320643277, 26380.3404914871, 26398.340041498348, 26759.598606021314, 26797.06210282631, 44289.77855110704, 44360.33459498046, 44672.10655786931, 45171.0965780676, 45509.54490455053, 30984.60569274433, 37203.813980929925, 37373.0, 37622.43566346452, 37795.43306850454, 37411.43882841705, 37847.81076094602, 38009.80995095007, 38012.80993595014, 38547.42178867375, 37101.814490927376, 37551.06122346854, 37704.81147594244, 37895.621043790314, 37911.81044094762, 31861.0, 33736.66263337335, 33857.98426047222, 33977.0, 34010.0, 24363.878180608983, 24929.376765581004, 25278.7360631964, 25566.48867022612, 25634.487310253317], "value": 18322.51824416613, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.3385475640597, 344.2272099137437, 357.1660173494889, 364.04695621702643, 372.68694296790693, 14424.639384015392, 14643.414263429464, 15389.153596552185, 15502.68994620107, 18265.54336141597, 18824.24703011878, 19018.334358297463, 19465.0, 19465.124069416874, 19688.60622787547, 5588.888222235553, 5866.853328666796, 5900.940990590104, 5907.995640741085, 5913.201717768089, 5591.916121258185, 5780.421957804228, 5848.795292164775, 5879.735411906453, 5889.911651325234, 5809.477147056764, 5842.883142337172, 5846.093855452412, 5849.912251316234, 5853.560982926277, 5548.722563871831, 5755.625884317506, 5831.30024397073, 5833.066709326487, 5840.328362238354, 5019.723915184663, 5067.9239811402485, 5136.845894623168, 5145.151050076733, 5145.176771716508, 18801.15394807237, 23582.0, 24197.395065123335, 24512.387190320205, 24744.010239590396, 2237.69791078204, 2472.8878241458024, 2618.973810261892, 2801.075645037153, 2996.4905965985836, 14717.705645887023, 14908.987286716263, 15068.246587670683, 15329.770053449096, 15459.922700386538, 1749.2041121289783, 1780.946571602854, 1828.4788835182042, 1877.5775450523522, 1916.6166766646727, 117644.0, 119834.0, 121406.99999999911, 123588.38205809004, 124311.0, 10644.201684873666, 10644.89355106439, 11073.114150868072, 11285.830712539318, 11288.492017859051, 13424.194548327132, 13858.445662173603, 13890.444382224598, 13895.444182232799, 13904.58286251414, 7157.534760240567, 8239.134890836496, 8500.53247071417, 8542.572871356408, 8647.524386158822, 6770.966145169243, 7013.333733295329, 8452.830943381097, 8468.364872634576, 8606.526641034803, 5955.642661440327, 6371.458426033746, 6441.420272175526, 6579.80260592183, 6588.143541339691, 1439.1073941377697, 1737.3224442467447, 1842.4012196036342, 1848.288408962559, 1849.1771161832855, 725.354434553248, 908.4594666173616, 916.3356566489242, 956.5552018311519, 986.1420564109271, 20238.0, 21627.567448650938, 21700.674489882666, 21732.674009889863, 21827.0, 877.2543338162511, 939.96710115147, 947.4362754161263, 951.6383774165772, 963.6868017894212, 9115.589798459085, 10960.616378426876, 11129.220954533104, 11138.0, 11299.943500282447, 240709.0, 388838.0, 596685.0, 620775.0, 623022.0, 50957.0, 61860.0, 67150.32124196934, 67612.0, 102938.45592316121, 972.3679608254549, 981.9116279534876, 982.0866594067443, 1004.9799004020025, 1017.3438132404558, 18268.615532272426, 18525.722114168027, 18669.34657287043, 18695.813041869405, 18728.7190692137, 51911.74044129755, 52222.21666674927, 53090.93818123539, 53322.0, 54104.458955409944, 54475.72762136164, 56491.71754141203, 66385.33614663985, 69401.0, 71945.6402717983, 7589.316961473494, 7701.0, 7843.764687059397, 7946.7615971520945, 8058.637361318872, 12300.446479908605, 12323.183888755952, 12598.937005314914, 12646.683832904251, 12698.492060317483, 8517.659293628185, 10976.890231097907, 11019.504122314354, 11111.38887361203, 11311.490982905625, 8458.74623761288, 10731.678049658523, 10772.946135269274, 10883.782324353628, 11037.944810275898, 10843.837342439712, 10887.0, 10912.799592045027, 10978.066864316303, 11412.657620271073, 7751.612419379121, 7935.1271360149185, 7998.960005199937, 8120.390970677105, 8153.388495862952, 10553.208509361675, 10731.0, 11004.284721493077, 11016.614418495314, 11246.66260012201], "value": 9239.07267899866, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1479.9778003329952, 1494.1259363272486, 1569.3957826236895, 1658.3781082094215, 1829.396299221257, 8001.519908805476, 8475.32197424206, 8481.533515656636, 8485.44844585103, 8498.277646400056, 5901.704914754256, 7444.925550744491, 7466.365358944497, 8058.758237252891, 8272.83454330913, 2283.0867652938873, 2318.431984163882, 2323.1288266899915, 2324.535092981402, 2330.0446816805115, 2326.93019209424, 2339.0643742502966, 2366.148186652804, 2373.8694371809543, 2375.714914210297, 2323.384303159664, 2328.9650655240184, 2357.9882100589557, 2360.7285162206394, 2361.787439130478, 2308.2728940383727, 2318.246569864792, 2334.136369543263, 2339.9181028664084, 2345.5191685704476, 1910.808919108092, 1941.6407964526609, 2338.5673650374733, 2344.120954642009, 2352.2590384028977, 8267.214614611603, 8338.749837504885, 8375.20435558621, 8385.6226469809, 8393.202645748644, 5034.874128146789, 5177.301064356301, 5189.610779191576, 5189.962007598496, 5200.973995130038, 3221.903342899717, 3292.6378098409155, 3415.829208539588, 3518.8768393106375, 3527.5061491391225, 1057.4501259345097, 1064.41989115932, 1084.8806631270554, 1095.9287646302964, 1104.536094840169, 10906.291091079134, 10983.121350291958, 11045.502952367162, 11061.502232399562, 11069.944650276777, 4449.1101779644205, 4568.223402021665, 4648.442186937589, 4648.861523699136, 4702.200625893606, 9417.717468475958, 9424.858627120599, 9434.622615095457, 9438.48088355147, 9445.527723613792, 5126.4617215191865, 5133.512316329943, 5134.768935397841, 5145.099607568735, 5150.948490515047, 4210.85262015828, 4474.217012022883, 4685.765711714469, 4697.8355757548325, 4736.242201247784, 3783.791891445997, 3798.487204227449, 3864.439656249884, 3899.74651647642, 3910.1202229498135, 1252.1985929005443, 1261.218044812204, 1263.8041103628952, 1269.7271883328738, 1275.853276873153, 845.849644483502, 847.2332539052168, 867.8741582470507, 918.2424499787702, 924.1036194890945, 5615.354234263031, 5657.179708942181, 5801.274840644919, 5810.983077961339, 6030.547708921848, 907.5598334807613, 912.2792993535033, 927.7170463008712, 927.958241879117, 961.4952150121243, 3695.2055308108884, 3749.9812500937865, 3754.680852127545, 3796.9430458542593, 3836.8081595920094, 9067.909320906838, 9457.574409151604, 9957.651482198233, 10339.586416543254, 10350.53422595985, 9183.760192373944, 9730.659426920165, 10072.0, 10304.227182961307, 10313.381197128196, 7625.771226863092, 7794.805129871798, 7915.564643944638, 7940.245676660708, 7947.165547617533, 5345.679259244458, 5444.8366549003595, 5465.9726701366235, 5575.609707320611, 5578.414266501959, 9356.204722598655, 9410.576524056298, 9495.05049495045, 9510.572024258787, 9565.04349565038, 9305.162535371848, 9473.66842160521, 9502.049795020443, 9565.186959108554, 9722.368046076983, 6315.5263355247625, 6524.869502610016, 6526.804195874131, 6755.729770809113, 6899.0, 7728.033995750531, 7934.801629959297, 8046.155153708776, 8116.066652334821, 8124.83750325002, 7071.646417678992, 7191.82020449472, 7311.561306321638, 7403.925960740539, 7434.070741157356, 6390.712417941297, 6889.724411023503, 6962.547434416747, 7110.751123710852, 7243.3843123333, 6695.263521012587, 6713.1944166700305, 6761.459083273425, 6775.1531058617675, 6848.863022739617, 4816.301636262652, 4826.710397376169, 4852.223644216909, 4905.313256144072, 4909.7545122743, 4781.211100168433, 4800.087983283165, 4820.71075735457, 4950.752462376939, 4991.301217829434], "value": 4411.99627535019, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.1426669790955, 296.1530024130985, 303.38867182627, 306.61060453224394, 309.1174696242227, 4587.830103323656, 5273.762680679369, 5299.443558426363, 5488.368837583679, 5513.393526712059, 4784.162771514988, 4792.424909010915, 5070.923936140961, 5203.583713302936, 5377.865553361168, 3487.581490221164, 3581.1736014632593, 3937.6259255370837, 4069.270560011983, 4117.649999750025, 3630.80030598317, 3776.622337766229, 4044.170944956277, 4065.8451020958937, 4075.839804903406, 3928.5874983126787, 3996.7002474814503, 4061.390791381287, 4067.450894129299, 4096.918061638766, 4067.491563554556, 4071.902969970453, 4074.4703188585295, 4077.9805048737817, 4109.732867363627, 3529.6117427083, 3540.150363912642, 3543.698785603227, 3548.6628770266784, 3555.928881422357, 4153.231652144363, 4252.489701235841, 5176.715280659562, 5181.5077567631015, 5250.298539529661, 3913.021744563859, 4106.835726570934, 4565.223911934979, 4625.444946606429, 4678.695884767513, 5176.378834539879, 5632.408597097327, 5656.585853536616, 5671.262735844355, 5683.5737319700775, 3268.921255985519, 3301.6203136639356, 3632.6398081774255, 3917.7061720370803, 3936.704747143947, 4606.930896036562, 4975.616580725105, 5018.648694591344, 5072.391313042384, 5711.314642242916, 3099.9225019374244, 3803.220339830328, 3825.6174382562076, 3844.75009124406, 3850.728070438455, 3895.240428116547, 3903.7748483364776, 3906.2539015096795, 3917.9020524486546, 3925.193486657054, 3901.49280593521, 4363.36731173978, 4573.947991961801, 4581.656375771764, 4605.0, 5549.364052075312, 5564.415736347625, 5617.578681598897, 5626.2967129108865, 5639.379668236573, 1094.4035500652005, 1197.5150064224003, 1251.787196176652, 1256.761215369077, 1283.0633637444719, 685.9382655560923, 805.8630032894422, 826.3017750001239, 834.1533343656193, 862.9654813807377, 3867.400552914303, 4572.565606267399, 4862.0, 4864.419063804207, 4964.400809413147, 5080.923786143211, 5328.866778330495, 5433.098415554524, 5490.69801160932, 5553.583481238923, 3993.5008123984503, 4645.279981602857, 4659.743714095757, 4706.7411292378565, 4921.87695307613, 2455.901763929423, 2663.4539919316494, 2709.279607449284, 2845.1506520761673, 2862.298736809495, 4807.7980504873785, 5083.491650834961, 5371.696406709845, 5539.473749993744, 5565.972170139124, 4861.489543597942, 5503.807366742145, 5551.3893471718075, 5888.941110588925, 5959.523238140939, 5508.44915508446, 5622.71071423222, 5914.0241860093465, 5996.310424301261, 6056.757729690763, 5581.330240371181, 5769.1634712967225, 5831.35855055935, 5876.236089308362, 5912.231409916855, 4843.837479004943, 5262.868428289324, 5412.404635490172, 5776.508996735324, 5843.912341314799, 5476.561875049907, 5787.131930210334, 5979.402059793986, 6079.726412311368, 6085.361037091042, 3708.295423869456, 3749.1939233065023, 3835.1782903120793, 3845.7307988441657, 3870.651641352292, 5827.562932779973, 5834.679092649945, 5840.269966254218, 5843.444872737083, 5873.324567674772, 5611.410801865909, 5611.663300202001, 5646.604737668324, 5726.828195154152, 5754.280714910637, 4709.646776491845, 4987.750612469289, 5409.61820925052, 5458.1266997280245, 5551.833444996657, 5021.874453138555, 5231.6397736588, 5416.83749487516, 5525.8948210357285, 5568.415316391721, 5121.385433747974, 5505.009098362173, 5545.504522964763, 5561.524047470331, 5582.655284780483, 4595.609373203315, 5458.0721277382145, 5545.944540554543, 5552.916706249329, 5556.6662001960085], "value": 4011.415947744935, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.2948735148995, 728.6101935464526, 764.121260550367, 770.0566805663067, 775.9495632783866, 13373.331333433325, 14070.507532236374, 14267.785983210248, 14279.714405711893, 14739.48411805587, 12800.487980480793, 13070.0, 13074.477020919176, 13075.476980920776, 13084.672883177926, 644.80011196529, 664.7939138866967, 802.3701394405407, 818.1491249100928, 821.3388222480901, 628.5600079944057, 804.8189157439588, 822.1244374740912, 835.340081335744, 836.5942517878824, 872.3239489395705, 873.7597160780805, 875.1642181716445, 875.1642181716477, 878.2622597018503, 675.8850995330805, 868.6699054359366, 871.333429926107, 872.2193636695154, 876.5310558850985, 512.861527387607, 518.1528201390715, 518.9584833213333, 519.8336532309662, 627.4635186915214, 13392.732145357038, 13885.583432497042, 13887.4445022199, 13900.165990040528, 13912.37394317258, 12606.243625382507, 12761.361931903368, 12832.294223817686, 12846.678833029155, 13172.275524846129, 10473.47632618366, 10795.784084318348, 10940.12479001678, 12007.279563226235, 12372.257664540157, 4384.232759267115, 4401.955980440187, 4432.645388368923, 4518.593326600589, 4561.612262957653, 83651.58174209151, 85202.0, 85338.0, 89009.55495222482, 89129.55435222847, 9831.360961537477, 9950.402975821475, 10406.356729629804, 10454.372737635766, 10466.52900619474, 19062.80937190638, 19065.42803715891, 19086.904565477085, 19092.618147637248, 19146.617067658848, 7234.385077268385, 7257.201707812136, 7259.89110163348, 7367.963160184165, 7615.885761713579, 4126.5350800466, 4178.143480586473, 4229.61933425987, 4310.741355518679, 4373.759443230653, 3046.558249053875, 3094.133642580081, 3111.626604807392, 3132.3578666373337, 3132.7493800495904, 616.315889362807, 628.7516431009725, 630.8012975912573, 639.1722719078804, 647.7369130196137, 519.2704250528014, 615.2770494668746, 655.3773914780882, 665.9884370114924, 679.3512195852949, 8859.601317940707, 8953.283737301, 9012.909870901207, 9176.72469825893, 9353.345265831462, 632.5287660692721, 646.8253571535658, 653.9934600654028, 670.6780745242311, 676.0670275020539, 4213.662906967496, 4499.505054444008, 4525.864224073283, 4552.70407423523, 4569.177548041318, 68712.0, 99717.50141249248, 104313.47843260736, 108879.0, 118732.0, 89496.0, 91737.0, 92431.0, 98885.01114988902, 99033.0, 85946.71079933806, 86409.0, 89688.5515572418, 89855.65216521757, 91051.0, 8630.78423039429, 8770.079141690287, 8852.424592401474, 8940.04202498537, 9002.639894404412, 48247.5175248243, 48647.27029094637, 51067.744661276454, 57365.0, 57455.850882982944, 65122.0, 69253.65373173101, 69647.65176174087, 70691.0, 75534.0, 11965.162438629213, 11985.0, 12072.577459788865, 12363.443645035815, 12549.498020079094, 13106.410211540313, 13340.0, 14015.089019213718, 14205.005649604842, 14774.409023639364, 12664.936675316565, 13139.73720525565, 13304.0, 13378.888689084557, 13389.531366402129, 13125.54060607874, 13740.244286563935, 13942.930285348508, 14148.292585370897, 14157.716845663235, 12284.631461056182, 12818.871811281768, 13501.797473037715, 13692.973027022814, 13800.964927630668, 9168.816623667355, 10181.592736290466, 10268.897311026794, 10595.94702026485, 10676.83984740245, 9374.484403357881, 9592.808143837225, 10293.3823970562, 11158.107351411705, 11458.369789661649], "value": 7148.784938016017, "unit": "score"}], "name": "dromaeo_css", "value": 7055.862495908137, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 114.0, 113.0, 116.0, 152.0, 113.0, 113.0, 113.0, 113.0], "value": 117.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 112.0, 111.0, 143.0, 117.0, 126.0, 111.0, 109.0, 138.0, 115.0], "value": 122.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 183.0, 186.0, 184.0, 182.0, 183.0, 181.0, 185.0, 185.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 60.0, 108.0, 84.0, 82.0, 61.0, 58.0, 82.0, 59.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 95.0, 69.0, 69.0, 68.0, 90.0, 68.0, 68.0, 92.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 89.0, 86.0, 88.0, 86.0, 90.0, 86.0, 89.0, 85.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 95.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 92.0, 93.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 84.0, 79.0, 82.0, 81.0, 82.0, 82.0, 82.0, 80.0], "value": 81.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 52.0, 54.0, 53.0, 54.0, 55.0, 54.0, 58.0, 81.0, 89.0], "value": 60.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 77.0, 82.0, 73.0, 72.0, 72.0, 72.0, 73.0, 72.0, 73.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 103.0, 102.0, 102.0, 83.0, 84.0, 83.0, 85.0, 90.0, 103.0], "value": 93.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 141.0, 149.0, 150.0, 140.0, 214.0, 175.0], "value": 155.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 166.0, 167.0, 169.0, 167.0, 167.0, 166.0, 165.0, 168.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 68.0, 77.0, 88.0, 85.0, 87.0, 71.0, 67.0], "value": 74.5, "unit": "score"}], "name": "kraken", "value": 1462.3000000000002, "alertThreshold": 2.0}]} 10:52:20 INFO - Return code: 0 10:52:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:52:20 ERROR - # TBPL SUCCESS # 10:52:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:52:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:52:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:52:20 INFO - 2016-11-14 10:52:20.189 screenresolution[1668:9824] starting screenresolution argv=screenresolution get 10:52:20 INFO - 2016-11-14 10:52:20.193 screenresolution[1668:9824] Display 0: 1600x1200x32@60 10:52:20 INFO - 2016-11-14 10:52:20.199 screenresolution[1669:9827] starting screenresolution argv=screenresolution list 10:52:20 INFO - Available Modes on Display 0 10:52:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:52:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:52:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:52:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:52:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:52:20 INFO - Intel Iris: 10:52:20 INFO - Chipset Model: Intel Iris 10:52:20 INFO - Type: GPU 10:52:20 INFO - Bus: Built-In 10:52:20 INFO - VRAM (Dynamic, Max): 1536 MB 10:52:20 INFO - Vendor: Intel (0x8086) 10:52:20 INFO - Device ID: 0x0a2e 10:52:20 INFO - Revision ID: 0x0009 10:52:20 INFO - Displays: 10:52:20 INFO - Display: 10:52:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:52:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:52:20 INFO - Display Serial Number: 1600x1200 60 10:52:20 INFO - Main Display: Yes 10:52:20 INFO - Mirror: Off 10:52:20 INFO - Online: Yes 10:52:20 INFO - Rotation: Supported 10:52:20 INFO - Return code: 0 10:52:20 INFO - Running post-action listener: _resource_record_post_action 10:52:20 INFO - [mozharness: 2016-11-14 18:52:20.436202Z] Finished run-tests step (success) 10:52:20 INFO - Running post-run listener: _resource_record_post_run 10:52:20 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 37483520; Write bytes: 650671616; Read time: 240; Write time: 1882 10:52:20 INFO - TinderboxPrint: CPU usage
53.3% 10:52:20 INFO - TinderboxPrint: I/O read bytes / time
37,483,520 / 240 10:52:20 INFO - TinderboxPrint: I/O write bytes / time
650,671,616 / 1,882 10:52:20 INFO - TinderboxPrint: CPU idle
2,264.8 (46.7%) 10:52:20 INFO - TinderboxPrint: CPU system
969.3 (20.0%) 10:52:20 INFO - TinderboxPrint: CPU user
1,615.6 (33.3%) 10:52:20 INFO - TinderboxPrint: Swap in / out
390,463,488 / 0 10:52:20 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354187776; Write bytes: 355386880; Read time: 27934; Write time: 754 10:52:20 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 34354176; Write bytes: 293888000; Read time: 218; Write time: 1120 10:52:20 INFO - Running post-run listener: _upload_blobber_files 10:52:20 INFO - Blob upload gear active. 10:52:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:52:20 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 10:52:20 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'] 10:52:20 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 10:52:21 INFO - (blobuploader) - INFO - Open directory for files ... 10:52:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:52:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:52:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:52:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:52:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:52:23 INFO - (blobuploader) - INFO - Done attempting. 10:52:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:52:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:52:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:52:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:52:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:52:24 INFO - (blobuploader) - INFO - Done attempting. 10:52:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:52:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:52:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:52:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:52:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:52:27 INFO - (blobuploader) - INFO - Done attempting. 10:52:27 INFO - (blobuploader) - INFO - Iteration through files over. 10:52:27 INFO - Return code: 0 10:52:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:52:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:52:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/609961619a9a4a616f43f708e888898f5c3b94cb1d167a80f4098b0127185dce2c28244cdd5f25329d7b8a6034efe81f89e3723630cf43f48ef210617ad6e761", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/721a83cc80e3d8919ce257984721e6ae3cdd1dc66b45995c1301fdd855a283ecb08a4e339b45684a8fc658fce45d4951d09ab2de70419337f7395148aa6323a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed8571ca3e682e39de05be954570af062b120a0da2e42c6f07d899962bb08bcc3a931fa6a9113db19629edfce4fc5590daab6bf86e2daf2d0c1f9f268ba0c786"} 10:52:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:52:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:52:27 INFO - Contents: 10:52:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/609961619a9a4a616f43f708e888898f5c3b94cb1d167a80f4098b0127185dce2c28244cdd5f25329d7b8a6034efe81f89e3723630cf43f48ef210617ad6e761", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/721a83cc80e3d8919ce257984721e6ae3cdd1dc66b45995c1301fdd855a283ecb08a4e339b45684a8fc658fce45d4951d09ab2de70419337f7395148aa6323a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed8571ca3e682e39de05be954570af062b120a0da2e42c6f07d899962bb08bcc3a931fa6a9113db19629edfce4fc5590daab6bf86e2daf2d0c1f9f268ba0c786"} 10:52:27 INFO - Running post-run listener: copy_logs_to_upload_dir 10:52:27 INFO - Copying logs to upload dir... 10:52:27 INFO - mkdir: /builds/slave/test/build/upload/logs 10:52:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.985897 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-14 10:52:27.392934) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 10:52:27.396553) ========= 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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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/609961619a9a4a616f43f708e888898f5c3b94cb1d167a80f4098b0127185dce2c28244cdd5f25329d7b8a6034efe81f89e3723630cf43f48ef210617ad6e761", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/721a83cc80e3d8919ce257984721e6ae3cdd1dc66b45995c1301fdd855a283ecb08a4e339b45684a8fc658fce45d4951d09ab2de70419337f7395148aa6323a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed8571ca3e682e39de05be954570af062b120a0da2e42c6f07d899962bb08bcc3a931fa6a9113db19629edfce4fc5590daab6bf86e2daf2d0c1f9f268ba0c786"} build_url:https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010866 build_url: 'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/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/609961619a9a4a616f43f708e888898f5c3b94cb1d167a80f4098b0127185dce2c28244cdd5f25329d7b8a6034efe81f89e3723630cf43f48ef210617ad6e761", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/721a83cc80e3d8919ce257984721e6ae3cdd1dc66b45995c1301fdd855a283ecb08a4e339b45684a8fc658fce45d4951d09ab2de70419337f7395148aa6323a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed8571ca3e682e39de05be954570af062b120a0da2e42c6f07d899962bb08bcc3a931fa6a9113db19629edfce4fc5590daab6bf86e2daf2d0c1f9f268ba0c786"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 10:52:27.429100) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:52:27.429443) ========= 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.pXEUoQ5YPo/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.BVr3WhFkuy/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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.004958 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:52:27.478111) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 10:52:27.478425) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 10:52:27.478759) ========= ========= Total master_lag: 0.50 =========