builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0159 starttime: 1480444433.24 results: success (0) buildid: 20161129080327 builduid: 25817b78612346fcb59aab790e7f7ca2 revision: af6fea4fe3c79e74b04f669ac3973cd69dd534a3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:53.239566) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:53.240144) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:53.240529) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:53.268212) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:53.268661) ========= 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.gAOp6HJMyp/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.irHuhbWSn3/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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-29 10:33:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 24.4M=0s 2016-11-29 10:33:53 (24.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.453956 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:53.747569) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:53.747948) ========= 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.gAOp6HJMyp/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.irHuhbWSn3/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.025156 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:53.799471) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 10:33:53.800272) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev af6fea4fe3c79e74b04f669ac3973cd69dd534a3 --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 af6fea4fe3c79e74b04f669ac3973cd69dd534a3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gAOp6HJMyp/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.irHuhbWSn3/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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-29 10:33:53,895 truncating revision to first 12 chars 2016-11-29 10:33:53,895 Setting DEBUG logging. 2016-11-29 10:33:53,895 attempt 1/10 2016-11-29 10:33:53,895 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/af6fea4fe3c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 10:33:54,976 unpacking tar archive at: mozilla-aurora-af6fea4fe3c7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.371780 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 10:33:55.198155) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:55.198579) ========= script_repo_revision: af6fea4fe3c79e74b04f669ac3973cd69dd534a3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:55.199085) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:55.199403) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:55.219911) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-29 10:33:55.220399) ========= /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.gAOp6HJMyp/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.irHuhbWSn3/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:33:55 INFO - MultiFileLogger online at 20161129 10:33:55 in /builds/slave/test 10:33:55 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:33:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:33:55 INFO - {'append_to_log': False, 10:33:55 INFO - 'base_work_dir': '/builds/slave/test', 10:33:55 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:33:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:33:55 INFO - 'branch': 'Mozilla-Aurora', 10:33:55 INFO - 'buildbot_json_path': 'buildprops.json', 10:33:55 INFO - 'config_files': ('talos/mac_config.py',), 10:33:55 INFO - 'default_actions': ('clobber', 10:33:55 INFO - 'read-buildbot-config', 10:33:55 INFO - 'download-and-extract', 10:33:55 INFO - 'populate-webroot', 10:33:55 INFO - 'create-virtualenv', 10:33:55 INFO - 'install', 10:33:55 INFO - 'run-tests'), 10:33:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:33:55 INFO - 'download_minidump_stackwalk': True, 10:33:55 INFO - 'download_symbols': 'ondemand', 10:33:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:33:55 INFO - 'tooltool.py': '/tools/tooltool.py', 10:33:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:33:55 INFO - '/tools/misc-python/virtualenv.py')}, 10:33:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:33:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:33:55 INFO - 'installer_path': 'installer.exe', 10:33:55 INFO - 'log_level': 'info', 10:33:55 INFO - 'log_name': 'talos', 10:33:55 INFO - 'log_to_console': True, 10:33:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:33:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:33:55 INFO - 'opt_config_files': (), 10:33:55 INFO - 'pip_index': False, 10:33:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:33:55 INFO - 'cmd': ('bash', 10:33:55 INFO - '-c', 10:33:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:33:55 INFO - 'enabled': True, 10:33:55 INFO - 'halt_on_failure': False, 10:33:55 INFO - 'name': 'check_screen_resolution'},), 10:33:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:33:55 INFO - 'cmd': ('bash', 10:33:55 INFO - '-c', 10:33:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:33:55 INFO - 'enabled': True, 10:33:55 INFO - 'halt_on_failure': False, 10:33:55 INFO - 'name': 'check_screen_resolution'},), 10:33:55 INFO - 'run_cmd_checks_enabled': True, 10:33:55 INFO - 'sps_profile': False, 10:33:55 INFO - 'sps_profile_interval': 0, 10:33:55 INFO - 'suite': 'dromaeojs', 10:33:55 INFO - 'system_bits': '32', 10:33:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:33:55 INFO - 'title': 't-yosemite-r7-0159', 10:33:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:33:55 INFO - 'use_talos_json': True, 10:33:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:33:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:33:55 INFO - 'work_dir': 'build'} 10:33:55 INFO - [mozharness: 2016-11-29 18:33:55.428845Z] Running clobber step. 10:33:55 INFO - Running pre-action listener: _resource_record_pre_action 10:33:55 INFO - Running main action method: clobber 10:33:55 INFO - rmtree: /builds/slave/test/build 10:33:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:33:57 INFO - Running post-action listener: _resource_record_post_action 10:33:57 INFO - [mozharness: 2016-11-29 18:33:57.916858Z] Finished clobber step (success) 10:33:57 INFO - [mozharness: 2016-11-29 18:33:57.916980Z] Running read-buildbot-config step. 10:33:57 INFO - Running pre-action listener: _resource_record_pre_action 10:33:57 INFO - Running main action method: read_buildbot_config 10:33:57 INFO - Using buildbot properties: 10:33:57 INFO - { 10:33:57 INFO - "project": "", 10:33:57 INFO - "product": "firefox", 10:33:57 INFO - "script_repo_revision": "production", 10:33:57 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:33:57 INFO - "repository": "", 10:33:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:33:57 INFO - "buildid": "20161129080327", 10:33:57 INFO - "pgo_build": "False", 10:33:57 INFO - "basedir": "/builds/slave/test", 10:33:57 INFO - "buildnumber": 5, 10:33:57 INFO - "slavename": "t-yosemite-r7-0159", 10:33:57 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:33:57 INFO - "platform": "yosemite_r7", 10:33:57 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:33:57 INFO - "branch": "mozilla-aurora", 10:33:57 INFO - "repo_path": "releases/mozilla-aurora", 10:33:57 INFO - "revision": "af6fea4fe3c79e74b04f669ac3973cd69dd534a3", 10:33:57 INFO - "stage_platform": "macosx64", 10:33:57 INFO - "builduid": "25817b78612346fcb59aab790e7f7ca2", 10:33:57 INFO - "slavebuilddir": "test" 10:33:57 INFO - } 10:33:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 10:33:57 INFO - Running post-action listener: _resource_record_post_action 10:33:57 INFO - [mozharness: 2016-11-29 18:33:57.920329Z] Finished read-buildbot-config step (success) 10:33:57 INFO - [mozharness: 2016-11-29 18:33:57.920473Z] Running download-and-extract step. 10:33:57 INFO - Running pre-action listener: _resource_record_pre_action 10:33:57 INFO - Running main action method: download_and_extract 10:33:57 INFO - mkdir: /builds/slave/test/build/tests 10:33:57 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:33:57 INFO - trying https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 10:33:57 INFO - Downloading https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/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:33:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/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:33:59 INFO - Downloaded 1199 bytes. 10:33:59 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:33:59 INFO - Using the following test package requirements: 10:33:59 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:33:59 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:33:59 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 10:33:59 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:33:59 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 10:33:59 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:33:59 INFO - u'jsshell-mac.zip'], 10:33:59 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:33:59 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 10:33:59 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:33:59 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:33:59 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 10:33:59 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:33:59 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 10:33:59 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:33:59 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 10:33:59 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:33:59 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 10:33:59 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:33:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:33:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:33:59 INFO - Fetch https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:33:59 INFO - Http code: 200 10:33:59 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 10:33:59 INFO - x-amz-cf-id: AXSQZjATzcV6HtISFjVFm_H4Cr5RWbjxeEkNCkcUoH3cLmhvM6iC5Q== 10:33:59 INFO - x-amz-version-id: qFR0z9xN2ZAdnm5elx9nVytzmU5_fmpX 10:34:00 INFO - Expected file size: 16282907 10:34:00 INFO - Obtained file size: 16282907 10:34:06 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:34:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:34:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:34:06 INFO - Fetch https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:34:07 INFO - Http code: 200 10:34:07 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 10:34:07 INFO - x-amz-cf-id: VAHsR169BuyhJOc0cEndMqTESJuCZoNvaKEpJEidMKVMtE4l2MZDBg== 10:34:07 INFO - x-amz-version-id: qFR0z9xN2ZAdnm5elx9nVytzmU5_fmpX 10:34:07 INFO - Expected file size: 16282907 10:34:07 INFO - Obtained file size: 16282907 10:34:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 10:34:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:34:13 INFO - Fetch https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 10:34:15 INFO - Http code: 200 10:34:15 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 10:34:15 INFO - x-amz-cf-id: vDu5jeUi3c51FhIAREV0dr0AJu8NBt--HeNEUKESaM4N-ltNbeTdsA== 10:34:15 INFO - x-amz-version-id: DXdjmidbrNT3016aiHp3McQ8tPLJT_bi 10:34:15 INFO - Expected file size: 14409038 10:34:15 INFO - Obtained file size: 14409038 10:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:34:15 INFO - trying https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:34:15 INFO - Downloading https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 10:34:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/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:34:18 INFO - Downloaded 109834644 bytes. 10:34:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:34:18 INFO - mkdir: /builds/slave/test/properties 10:34:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:34:18 INFO - Writing to file /builds/slave/test/properties/build_url 10:34:18 INFO - Contents: 10:34:18 INFO - build_url:https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:34:19 INFO - Running post-action listener: _resource_record_post_action 10:34:19 INFO - Running post-action listener: set_extra_try_arguments 10:34:19 INFO - [mozharness: 2016-11-29 18:34:19.226812Z] Finished download-and-extract step (success) 10:34:19 INFO - [mozharness: 2016-11-29 18:34:19.226957Z] Running populate-webroot step. 10:34:19 INFO - Running pre-action listener: _resource_record_pre_action 10:34:19 INFO - Running main action method: populate_webroot 10:34:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:34:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:34:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:34:19 INFO - '--tppagecycles', 10:34:19 INFO - '7'], 10:34:19 INFO - 'tests': ['tsvgm']}}, 10:34:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:34:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:34:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:34:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:34:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:34:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:34:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:19 INFO - 'talos_options': ['--disable-e10s'], 10:34:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:34:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:34:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:19 INFO - 'talos_options': ['--disable-e10s'], 10:34:19 INFO - 'tests': ['damp', 'tps']}, 10:34:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:19 INFO - 'tests': ['damp', 'tps']}, 10:34:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:34:19 INFO - 'tests': ['dromaeo_dom']}, 10:34:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:34:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:34:19 INFO - 'tests': ['basic_compositor_video']}, 10:34:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:34:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:34:19 INFO - 'tests': ['a11yr', 10:34:19 INFO - 'ts_paint', 10:34:19 INFO - 'tpaint', 10:34:19 INFO - 'sessionrestore', 10:34:19 INFO - 'sessionrestore_no_auto_restore', 10:34:19 INFO - 'tabpaint']}, 10:34:19 INFO - 'other-e10s': {'tests': ['a11yr', 10:34:19 INFO - 'ts_paint', 10:34:19 INFO - 'tpaint', 10:34:19 INFO - 'sessionrestore', 10:34:19 INFO - 'sessionrestore_no_auto_restore', 10:34:19 INFO - 'tabpaint']}, 10:34:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:34:19 INFO - 'ts_paint', 10:34:19 INFO - 'tpaint', 10:34:19 INFO - 'sessionrestore', 10:34:19 INFO - 'sessionrestore_no_auto_restore', 10:34:19 INFO - 'tabpaint']}, 10:34:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:34:19 INFO - 'ts_paint', 10:34:19 INFO - 'tpaint', 10:34:19 INFO - 'sessionrestore', 10:34:19 INFO - 'sessionrestore_no_auto_restore', 10:34:19 INFO - 'tabpaint']}, 10:34:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:34:19 INFO - 'tests': ['a11yr', 10:34:19 INFO - 'ts_paint', 10:34:19 INFO - 'tpaint', 10:34:19 INFO - 'sessionrestore', 10:34:19 INFO - 'sessionrestore_no_auto_restore', 10:34:19 INFO - 'tabpaint']}, 10:34:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:34:19 INFO - 'tests': ['a11yr', 10:34:19 INFO - 'ts_paint', 10:34:19 INFO - 'tpaint', 10:34:19 INFO - 'sessionrestore', 10:34:19 INFO - 'sessionrestore_no_auto_restore', 10:34:19 INFO - 'tabpaint']}, 10:34:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:34:19 INFO - 'tests': ['tsvgx', 10:34:19 INFO - 'tsvgr_opacity', 10:34:19 INFO - 'tart', 10:34:19 INFO - 'tscrollx', 10:34:19 INFO - 'cart']}, 10:34:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:34:19 INFO - 'tsvgr_opacity', 10:34:19 INFO - 'tart', 10:34:19 INFO - 'tscrollx', 10:34:19 INFO - 'cart']}, 10:34:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:19 INFO - 'talos_options': ['--disable-e10s'], 10:34:19 INFO - 'tests': ['tp5o']}, 10:34:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:19 INFO - 'tests': ['tp5o']}, 10:34:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:19 INFO - 'talos_options': ['--disable-e10s', 10:34:19 INFO - '--xperf_path', 10:34:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:34:19 INFO - 'tests': ['tp5n']}, 10:34:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:34:19 INFO - 'talos_options': ['--xperf_path', 10:34:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:34:19 INFO - 'tests': ['tp5n']}}, 10:34:19 INFO - 'talos.zip': {'path': '', 10:34:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:34:19 INFO - Running post-action listener: _resource_record_post_action 10:34:19 INFO - [mozharness: 2016-11-29 18:34:19.245894Z] Finished populate-webroot step (success) 10:34:19 INFO - [mozharness: 2016-11-29 18:34:19.246026Z] Running create-virtualenv step. 10:34:19 INFO - Running pre-action listener: _resource_record_pre_action 10:34:19 INFO - Running main action method: create_virtualenv 10:34:19 INFO - Creating virtualenv /builds/slave/test/build/venv 10:34:19 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:34:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:34:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:34:19 INFO - Using real prefix '/tools/python27' 10:34:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:34:20 INFO - Installing distribute.............................................................................................................................................................................................done. 10:34:22 INFO - Installing pip.................done. 10:34:22 INFO - Return code: 0 10:34:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:34:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:34:23 INFO - Reading from file tmpfile_stdout 10:34:23 INFO - Output received: 10:34:23 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:34:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:34:23 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:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:23 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:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:23 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 0x10c740490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa040db5d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554d30>, '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 0x10c3badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c54cb20>, '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.gAOp6HJMyp/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.irHuhbWSn3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:34:23 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:34:23 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:34:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAOp6HJMyp/Render', 10:34:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:34:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:34:23 INFO - 'HOME': '/Users/cltbld', 10:34:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:34:23 INFO - 'LOGNAME': 'cltbld', 10:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:23 INFO - 'MOZ_NO_REMOTE': '1', 10:34:23 INFO - 'NO_EM_RESTART': '1', 10:34:23 INFO - 'PAGER': '/bin/cat', 10:34:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:34:23 INFO - 'PWD': '/builds/slave/test', 10:34:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:34:23 INFO - 'SHELL': '/bin/bash', 10:34:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.irHuhbWSn3/Listeners', 10:34:23 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 10:34:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:34:23 INFO - 'USER': 'cltbld', 10:34:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:34:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:23 INFO - 'XPC_FLAGS': '0x0', 10:34:23 INFO - 'XPC_SERVICE_NAME': '0', 10:34:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:23 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:34:23 INFO - Cleaning up... 10:34:23 INFO - Return code: 0 10:34:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:34:23 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:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:23 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:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:23 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 0x10c740490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa040db5d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554d30>, '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 0x10c3badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c54cb20>, '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.gAOp6HJMyp/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.irHuhbWSn3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:34:23 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:34:23 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:34:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAOp6HJMyp/Render', 10:34:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:34:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:34:23 INFO - 'HOME': '/Users/cltbld', 10:34:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:34:23 INFO - 'LOGNAME': 'cltbld', 10:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:23 INFO - 'MOZ_NO_REMOTE': '1', 10:34:23 INFO - 'NO_EM_RESTART': '1', 10:34:23 INFO - 'PAGER': '/bin/cat', 10:34:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:34:23 INFO - 'PWD': '/builds/slave/test', 10:34:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:34:23 INFO - 'SHELL': '/bin/bash', 10:34:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.irHuhbWSn3/Listeners', 10:34:23 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 10:34:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:34:23 INFO - 'USER': 'cltbld', 10:34:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:34:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:23 INFO - 'XPC_FLAGS': '0x0', 10:34:23 INFO - 'XPC_SERVICE_NAME': '0', 10:34:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:23 INFO - Downloading/unpacking psutil>=3.1.1 10:34:23 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:34:23 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:34:23 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:34:23 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:34:23 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:34:23 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:34:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:34:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:34:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:34:27 INFO - Installing collected packages: psutil 10:34:27 INFO - Running setup.py install for psutil 10:34:27 INFO - building 'psutil._psutil_osx' extension 10:34:27 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:34:27 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:34:28 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:34:28 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:34:28 INFO - building 'psutil._psutil_posix' extension 10:34:28 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:34:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:34:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:34:28 INFO - ^ 10:34:28 INFO - 1 warning generated. 10:34:28 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:34:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:34:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:34:28 INFO - Successfully installed psutil 10:34:28 INFO - Cleaning up... 10:34:28 INFO - Return code: 0 10:34:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c740490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa040db5d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554d30>, '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 0x10c3badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c54cb20>, '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.gAOp6HJMyp/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.irHuhbWSn3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:34:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:34:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:34:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAOp6HJMyp/Render', 10:34:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:34:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:34:28 INFO - 'HOME': '/Users/cltbld', 10:34:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:34:28 INFO - 'LOGNAME': 'cltbld', 10:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:28 INFO - 'MOZ_NO_REMOTE': '1', 10:34:28 INFO - 'NO_EM_RESTART': '1', 10:34:28 INFO - 'PAGER': '/bin/cat', 10:34:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:34:28 INFO - 'PWD': '/builds/slave/test', 10:34:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:34:28 INFO - 'SHELL': '/bin/bash', 10:34:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.irHuhbWSn3/Listeners', 10:34:28 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 10:34:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:34:28 INFO - 'USER': 'cltbld', 10:34:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:34:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:28 INFO - 'XPC_FLAGS': '0x0', 10:34:28 INFO - 'XPC_SERVICE_NAME': '0', 10:34:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:34:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:34:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:34:31 INFO - Installing collected packages: mozsystemmonitor 10:34:31 INFO - Running setup.py install for mozsystemmonitor 10:34:31 INFO - Successfully installed mozsystemmonitor 10:34:31 INFO - Cleaning up... 10:34:31 INFO - Return code: 0 10:34:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:34:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:34:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c740490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa040db5d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554d30>, '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 0x10c3badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c54cb20>, '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.gAOp6HJMyp/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.irHuhbWSn3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:34:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:34:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAOp6HJMyp/Render', 10:34:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:34:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:34:31 INFO - 'HOME': '/Users/cltbld', 10:34:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:34:31 INFO - 'LOGNAME': 'cltbld', 10:34:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:31 INFO - 'MOZ_NO_REMOTE': '1', 10:34:31 INFO - 'NO_EM_RESTART': '1', 10:34:31 INFO - 'PAGER': '/bin/cat', 10:34:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:34:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:34:31 INFO - 'PWD': '/builds/slave/test', 10:34:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:34:31 INFO - 'SHELL': '/bin/bash', 10:34:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.irHuhbWSn3/Listeners', 10:34:31 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 10:34:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:34:31 INFO - 'USER': 'cltbld', 10:34:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:34:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:34:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:31 INFO - 'XPC_FLAGS': '0x0', 10:34:31 INFO - 'XPC_SERVICE_NAME': '0', 10:34:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:34:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:31 INFO - Downloading/unpacking jsonschema==2.5.1 10:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:34:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:34:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:34 INFO - Downloading functools32-3.2.3-2.tar.gz 10:34:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:34:35 INFO - warning: no files found matching '*.txt' 10:34:35 INFO - no previously-included directories found matching 'build' 10:34:35 INFO - no previously-included directories found matching 'dist' 10:34:35 INFO - no previously-included directories found matching '.git*' 10:34:35 INFO - Installing collected packages: jsonschema, functools32 10:34:35 INFO - Running setup.py install for functools32 10:34:35 INFO - warning: no files found matching '*.txt' 10:34:35 INFO - no previously-included directories found matching 'build' 10:34:35 INFO - no previously-included directories found matching 'dist' 10:34:35 INFO - no previously-included directories found matching '.git*' 10:34:35 INFO - Successfully installed jsonschema functools32 10:34:35 INFO - Cleaning up... 10:34:35 INFO - Return code: 0 10:34:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:34:35 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:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:35 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:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:35 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 0x10c740490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa040db5d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554d30>, '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 0x10c3badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c54cb20>, '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.gAOp6HJMyp/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.irHuhbWSn3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:34:35 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:34:35 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:34:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAOp6HJMyp/Render', 10:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:34:35 INFO - 'HOME': '/Users/cltbld', 10:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:34:35 INFO - 'LOGNAME': 'cltbld', 10:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:35 INFO - 'MOZ_NO_REMOTE': '1', 10:34:35 INFO - 'NO_EM_RESTART': '1', 10:34:35 INFO - 'PAGER': '/bin/cat', 10:34:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:34:35 INFO - 'PWD': '/builds/slave/test', 10:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:34:35 INFO - 'SHELL': '/bin/bash', 10:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.irHuhbWSn3/Listeners', 10:34:35 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 10:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:34:35 INFO - 'USER': 'cltbld', 10:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:35 INFO - 'XPC_FLAGS': '0x0', 10:34:35 INFO - 'XPC_SERVICE_NAME': '0', 10:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:34:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:34:35 INFO - Cleaning up... 10:34:35 INFO - Return code: 0 10:34:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:34:35 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:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:35 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:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:35 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 0x10c740490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa040db5d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554d30>, '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 0x10c3badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c54cb20>, '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.gAOp6HJMyp/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.irHuhbWSn3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:34:35 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:34:35 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:34:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAOp6HJMyp/Render', 10:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:34:35 INFO - 'HOME': '/Users/cltbld', 10:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:34:35 INFO - 'LOGNAME': 'cltbld', 10:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:35 INFO - 'MOZ_NO_REMOTE': '1', 10:34:35 INFO - 'NO_EM_RESTART': '1', 10:34:35 INFO - 'PAGER': '/bin/cat', 10:34:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:34:35 INFO - 'PWD': '/builds/slave/test', 10:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:34:35 INFO - 'SHELL': '/bin/bash', 10:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.irHuhbWSn3/Listeners', 10:34:35 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 10:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:34:35 INFO - 'USER': 'cltbld', 10:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:35 INFO - 'XPC_FLAGS': '0x0', 10:34:35 INFO - 'XPC_SERVICE_NAME': '0', 10:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:34:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:35 INFO - Downloading/unpacking blobuploader==1.2.4 10:34:35 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:34:35 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:34:35 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:34:35 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:34:35 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:34:35 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:34:38 INFO - Downloading blobuploader-1.2.4.tar.gz 10:34:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:34:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:34: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:34: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:34: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:34: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:34: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:34: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:34:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:34:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:34:39 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:34:39 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:34:39 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:34:39 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:34:39 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:34:39 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:34:39 INFO - Downloading docopt-0.6.1.tar.gz 10:34:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:34:39 INFO - Installing collected packages: blobuploader, requests, docopt 10:34:39 INFO - Running setup.py install for blobuploader 10:34:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:34:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:34:39 INFO - Running setup.py install for requests 10:34:40 INFO - Running setup.py install for docopt 10:34:40 INFO - Successfully installed blobuploader requests docopt 10:34:40 INFO - Cleaning up... 10:34:40 INFO - Return code: 0 10:34:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:34:40 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:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:40 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:34:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:40 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 0x10c740490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa040db5d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554d30>, '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 0x10c3badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c54cb20>, '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.gAOp6HJMyp/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.irHuhbWSn3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:34:40 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:34:40 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:34:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAOp6HJMyp/Render', 10:34:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:34:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:34:40 INFO - 'HOME': '/Users/cltbld', 10:34:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:34:40 INFO - 'LOGNAME': 'cltbld', 10:34:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:40 INFO - 'MOZ_NO_REMOTE': '1', 10:34:40 INFO - 'NO_EM_RESTART': '1', 10:34:40 INFO - 'PAGER': '/bin/cat', 10:34:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:34:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:34:40 INFO - 'PWD': '/builds/slave/test', 10:34:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:34:40 INFO - 'SHELL': '/bin/bash', 10:34:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.irHuhbWSn3/Listeners', 10:34:40 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 10:34:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:34:40 INFO - 'USER': 'cltbld', 10:34:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:34:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:34:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:40 INFO - 'XPC_FLAGS': '0x0', 10:34:40 INFO - 'XPC_SERVICE_NAME': '0', 10:34:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:34:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:34:40 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-0OnJAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:34:41 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-lf_1kX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:34:41 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-ofekef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:34:41 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Ijt8iN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:34:41 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-tTZM2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:34:41 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-yvL6qP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:34:41 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-7UYq11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:34:41 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Jx4rI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:34:41 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-oDDUeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:34:41 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-PexNdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:34:41 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-B9kRrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:34:42 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-kHEr2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:34:42 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-S9XgzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:34:42 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-AHg5x1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:34:42 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-jHYWvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:34:42 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-6if6JV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:34:42 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-ZGM3Qz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:34:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:34:42 INFO - Running setup.py install for manifestparser 10:34:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:34:42 INFO - Running setup.py install for mozcrash 10:34:42 INFO - Running setup.py install for mozdebug 10:34:43 INFO - Running setup.py install for mozdevice 10:34:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:34:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:34:43 INFO - Running setup.py install for mozfile 10:34:43 INFO - Running setup.py install for mozhttpd 10:34:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:34:43 INFO - Running setup.py install for mozinfo 10:34:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:34:43 INFO - Running setup.py install for mozInstall 10:34:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:34:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:34:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:34:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:34:43 INFO - Running setup.py install for mozleak 10:34:43 INFO - Running setup.py install for mozlog 10:34:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:34:44 INFO - Running setup.py install for moznetwork 10:34:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:34:44 INFO - Running setup.py install for mozprocess 10:34:44 INFO - Running setup.py install for mozprofile 10:34:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:34:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:34:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:34:44 INFO - Running setup.py install for mozrunner 10:34:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:34:44 INFO - Running setup.py install for mozscreenshot 10:34:44 INFO - Running setup.py install for moztest 10:34:45 INFO - Running setup.py install for mozversion 10:34:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:34:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:34:45 INFO - Cleaning up... 10:34:45 INFO - Return code: 0 10:34:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:34:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:34:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:45 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 0x10c740490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa040db5d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554d30>, '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 0x10c3badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c54cb20>, '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.gAOp6HJMyp/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.irHuhbWSn3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:34:45 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:34:45 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:34:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAOp6HJMyp/Render', 10:34:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:34:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:34:45 INFO - 'HOME': '/Users/cltbld', 10:34:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:34:45 INFO - 'LOGNAME': 'cltbld', 10:34:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:45 INFO - 'MOZ_NO_REMOTE': '1', 10:34:45 INFO - 'NO_EM_RESTART': '1', 10:34:45 INFO - 'PAGER': '/bin/cat', 10:34:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:34:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:34:45 INFO - 'PWD': '/builds/slave/test', 10:34:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:34:45 INFO - 'SHELL': '/bin/bash', 10:34:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.irHuhbWSn3/Listeners', 10:34:45 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 10:34:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:34:45 INFO - 'USER': 'cltbld', 10:34:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:34:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:34:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:45 INFO - 'XPC_FLAGS': '0x0', 10:34:45 INFO - 'XPC_SERVICE_NAME': '0', 10:34:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:34:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:34:45 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-GWETGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:34:45 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:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:34:45 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-ue_cT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:34:45 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:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:34:45 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-o52qJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:34:45 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:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:34:45 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-kuMZlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:34:45 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:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:34:45 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-iL0hc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:34:46 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:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:34:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-07nw_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:34:46 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:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:34:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-VWPC0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:34:46 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:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:34:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-i_a_oE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:34:46 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:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:34:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-vAn_2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:34:46 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:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:34:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-kda7oZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:34:46 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:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:34:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-2PT1vo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:34:46 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:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:34:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-T_ja4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:34:46 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:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:34:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-fTRxVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:34:46 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:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:34:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-NMHv3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:34:47 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:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:34:47 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-EyxIiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:34:47 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:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:34:47 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-gdoqwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:34:47 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:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:34:47 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-xVz2bc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:34:47 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:34:47 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:34:47 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:34:47 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:34:47 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:34:47 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:34:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:34:47 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:34:47 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:34:47 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:34:47 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:34:47 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:34:47 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:34:49 INFO - Downloading blessings-1.6.tar.gz 10:34:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:34:49 INFO - Installing collected packages: blessings 10:34:49 INFO - Running setup.py install for blessings 10:34:50 INFO - Successfully installed blessings 10:34:50 INFO - Cleaning up... 10:34:50 INFO - Return code: 0 10:34:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:34:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:34:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:34:50 INFO - Reading from file tmpfile_stdout 10:34:50 INFO - Current package versions: 10:34:50 INFO - blessings == 1.6 10:34:50 INFO - blobuploader == 1.2.4 10:34:50 INFO - docopt == 0.6.1 10:34:50 INFO - functools32 == 3.2.3-2 10:34:50 INFO - jsonschema == 2.5.1 10:34:50 INFO - manifestparser == 1.1 10:34:50 INFO - mozInstall == 1.12 10:34:50 INFO - mozcrash == 1.0 10:34:50 INFO - mozdebug == 0.1 10:34:50 INFO - mozdevice == 0.48 10:34:50 INFO - mozfile == 1.2 10:34:50 INFO - mozhttpd == 0.7 10:34:50 INFO - mozinfo == 0.9 10:34:50 INFO - mozleak == 0.1 10:34:50 INFO - mozlog == 3.4 10:34:50 INFO - moznetwork == 0.27 10:34:50 INFO - mozprocess == 0.23 10:34:50 INFO - mozprofile == 0.28 10:34:50 INFO - mozrunner == 6.13 10:34:50 INFO - mozscreenshot == 0.1 10:34:50 INFO - mozsystemmonitor == 0.3 10:34:50 INFO - moztest == 0.7 10:34:50 INFO - mozversion == 1.4 10:34:50 INFO - psutil == 3.1.1 10:34:50 INFO - requests == 1.2.3 10:34:50 INFO - wsgiref == 0.1.2 10:34:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:34: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:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34: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:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:51 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 0x10c740490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa040db5d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554d30>, '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 0x10c3badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c54cb20>, '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.gAOp6HJMyp/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.irHuhbWSn3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:34:51 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:34:51 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:34:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAOp6HJMyp/Render', 10:34:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:34:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:34:51 INFO - 'HOME': '/Users/cltbld', 10:34:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:34:51 INFO - 'LOGNAME': 'cltbld', 10:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:51 INFO - 'MOZ_NO_REMOTE': '1', 10:34:51 INFO - 'NO_EM_RESTART': '1', 10:34:51 INFO - 'PAGER': '/bin/cat', 10:34:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:34:51 INFO - 'PWD': '/builds/slave/test', 10:34:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:34:51 INFO - 'SHELL': '/bin/bash', 10:34:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.irHuhbWSn3/Listeners', 10:34:51 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 10:34:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:34:51 INFO - 'USER': 'cltbld', 10:34:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:34:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:51 INFO - 'XPC_FLAGS': '0x0', 10:34:51 INFO - 'XPC_SERVICE_NAME': '0', 10:34:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:34:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:51 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:34:51 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:34:51 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:34:51 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:34:51 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:34:51 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:34:51 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:34:51 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:34:51 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:34:51 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:34:51 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:34:51 INFO - Cleaning up... 10:34:51 INFO - Return code: 0 10:34:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:34:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:34:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:51 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 0x10c740490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa040db5d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554d30>, '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 0x10c3badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c54cb20>, '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.gAOp6HJMyp/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.irHuhbWSn3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:34:51 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:34:51 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:34:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAOp6HJMyp/Render', 10:34:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:34:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:34:51 INFO - 'HOME': '/Users/cltbld', 10:34:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:34:51 INFO - 'LOGNAME': 'cltbld', 10:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:51 INFO - 'MOZ_NO_REMOTE': '1', 10:34:51 INFO - 'NO_EM_RESTART': '1', 10:34:51 INFO - 'PAGER': '/bin/cat', 10:34:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:34:51 INFO - 'PWD': '/builds/slave/test', 10:34:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:34:51 INFO - 'SHELL': '/bin/bash', 10:34:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.irHuhbWSn3/Listeners', 10:34:51 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 10:34:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:34:51 INFO - 'USER': 'cltbld', 10:34:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:34:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:51 INFO - 'XPC_FLAGS': '0x0', 10:34:51 INFO - 'XPC_SERVICE_NAME': '0', 10:34:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:34:52 INFO - Cleaning up... 10:34:52 INFO - Return code: 0 10:34:52 INFO - Running post-action listener: _resource_record_post_action 10:34:52 INFO - Running post-action listener: _start_resource_monitoring 10:34:52 INFO - Starting resource monitoring. 10:34:52 INFO - [mozharness: 2016-11-29 18:34:52.234633Z] Finished create-virtualenv step (success) 10:34:52 INFO - [mozharness: 2016-11-29 18:34:52.235677Z] Running install step. 10:34:52 INFO - Running pre-action listener: _resource_record_pre_action 10:34:52 INFO - Running main action method: install 10:34:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:34:52 INFO - Reading from file tmpfile_stdout 10:34:52 INFO - Detecting whether we're running mozinstall >=1.0... 10:34:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:34:52 INFO - Reading from file tmpfile_stdout 10:34:52 INFO - Output received: 10:34:52 INFO - Usage: mozinstall [options] installer 10:34:52 INFO - Options: 10:34:52 INFO - -h, --help show this help message and exit 10:34:52 INFO - -d DEST, --destination=DEST 10:34:52 INFO - Directory to install application into. [default: 10:34:52 INFO - "/builds/slave/test"] 10:34:52 INFO - --app=APP Application being installed. [default: firefox] 10:34:52 INFO - mkdir: /builds/slave/test/build/application 10:34:52 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:34:52 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:35:23 INFO - Reading from file tmpfile_stdout 10:35:23 INFO - Output received: 10:35:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:35:23 INFO - Running post-action listener: _resource_record_post_action 10:35:23 INFO - [mozharness: 2016-11-29 18:35:23.893031Z] Finished install step (success) 10:35:23 INFO - [mozharness: 2016-11-29 18:35:23.893166Z] Running run-tests step. 10:35:23 INFO - Running pre-action listener: _resource_record_pre_action 10:35:23 INFO - Running main action method: run_tests 10:35:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:35:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:35:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:35:23 INFO - 2016-11-29 10:35:23.907 screenresolution[1624:7335] starting screenresolution argv=screenresolution get 10:35:23 INFO - 2016-11-29 10:35:23.910 screenresolution[1624:7335] Display 0: 1600x1200x32@60 10:35:23 INFO - 2016-11-29 10:35:23.915 screenresolution[1625:7337] starting screenresolution argv=screenresolution list 10:35:23 INFO - Available Modes on Display 0 10:35:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:35:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:35:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:35:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:35:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:35:24 INFO - Intel Iris: 10:35:24 INFO - Chipset Model: Intel Iris 10:35:24 INFO - Type: GPU 10:35:24 INFO - Bus: Built-In 10:35:24 INFO - VRAM (Dynamic, Max): 1536 MB 10:35:24 INFO - Vendor: Intel (0x8086) 10:35:24 INFO - Device ID: 0x0a2e 10:35:24 INFO - Revision ID: 0x0009 10:35:24 INFO - Displays: 10:35:24 INFO - Display: 10:35:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:35:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:35:24 INFO - Display Serial Number: 1600x1200 60 10:35:24 INFO - Main Display: Yes 10:35:24 INFO - Mirror: Off 10:35:24 INFO - Online: Yes 10:35:24 INFO - Rotation: Supported 10:35:24 INFO - Return code: 0 10:35:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:35:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:35:24 INFO - Python 2.7.3 10:35:24 INFO - Return code: 0 10:35:24 INFO - grabbing minidump binary from tooltool 10:35:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:35:24 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 0x10c554d30>, '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 0x10c3badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c54cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:35:24 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:35:24 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:35:24 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:35:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:35:24 INFO - Return code: 0 10:35:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:35:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:35:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:35:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:35:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:35:24 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/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--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:35:24 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/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0159 --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:35:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAOp6HJMyp/Render', 10:35:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:35:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:35:24 INFO - 'HOME': '/Users/cltbld', 10:35:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:35:24 INFO - 'LOGNAME': 'cltbld', 10:35:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:35:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:35:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:35:24 INFO - 'MOZ_NO_REMOTE': '1', 10:35:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:35:24 INFO - 'NO_EM_RESTART': '1', 10:35:24 INFO - 'PAGER': '/bin/cat', 10:35:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:35:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:35:24 INFO - 'PWD': '/builds/slave/test', 10:35:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:35:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:35:24 INFO - 'SHELL': '/bin/bash', 10:35:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.irHuhbWSn3/Listeners', 10:35:24 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 10:35:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:35:24 INFO - 'USER': 'cltbld', 10:35:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:35:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:35:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:35:24 INFO - 'XPC_FLAGS': '0x0', 10:35:24 INFO - 'XPC_SERVICE_NAME': '0', 10:35:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:35:24 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/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--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:35:24 INFO - mozversion application_buildid: 20161129080327 10:35:24 INFO - mozversion application_changeset: af6fea4fe3c79e74b04f669ac3973cd69dd534a3 10:35:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:35:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:35:24 INFO - mozversion application_name: Firefox 10:35:24 INFO - mozversion application_remotingname: firefox-dev 10:35:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:35:24 INFO - mozversion application_vendor: Mozilla 10:35:24 INFO - mozversion application_version: 52.0a2 10:35:24 INFO - mozversion platform_buildid: 20161129080327 10:35:24 INFO - mozversion platform_changeset: af6fea4fe3c79e74b04f669ac3973cd69dd534a3 10:35:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:35:24 INFO - mozversion platform_version: 52.0a2 10:35:24 INFO - using testdate: 1480444524 10:35:24 INFO - actual date: 1480444524 10:35:24 INFO - starting webserver on 'localhost:49236' 10:35:24 INFO - SUITE-START | Running 2 tests 10:35:24 INFO - TEST-START | dromaeo_css 10:35:24 INFO - operating with platform_type : mac_ 10:35:24 INFO - Initialising browser for dromaeo_css test... 10:35:24 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpcA6Jup/profile http://localhost:49236/getInfo.html) 10:35:27 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 10:35:27 INFO - PROCESS | 1635 | colorDepth:24 10:35:27 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 10:35:27 INFO - PROCESS | 1635 | __metrics 10:35:27 INFO - PROCESS | 1635 | 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:35:27 INFO - PROCESS | 1635 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:35:28 INFO - TEST-INFO | 1635: exit 0 10:35:28 INFO - Browser initialized. 10:35:28 INFO - Running cycle 1/1 for dromaeo_css test... 10:35:28 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpcA6Jup/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:35:40 INFO - PROCESS | 1636 | RSS: Main: 223076352 10:35:40 INFO - PROCESS | 1636 | 10:36:31 INFO - PROCESS | 1636 | 1480444591810 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:38:44 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 10:38:46 INFO - PROCESS | 1636 | RSS: Main: 356511744 10:38:46 INFO - PROCESS | 1636 | 10:41:49 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 10:41:50 INFO - PROCESS | 1636 | RSS: Main: 348172288 10:41:50 INFO - PROCESS | 1636 | 10:44:52 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 10:44:53 INFO - PROCESS | 1636 | RSS: Main: 332292096 10:44:53 INFO - PROCESS | 1636 | 10:47:55 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 10:47:56 INFO - PROCESS | 1636 | RSS: Main: 310542336 10:47:56 INFO - PROCESS | 1636 | 10:50:59 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 10:51:00 INFO - PROCESS | 1636 | RSS: Main: 306397184 10:51:00 INFO - PROCESS | 1636 | 10:54:03 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 10:54:03 INFO - PROCESS | 1636 | RSS: Main: 372977664 10:54:03 INFO - PROCESS | 1636 | 10:54:03 INFO - PROCESS | 1636 | __start_tp_report 10:54:03 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 10:54:03 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 10:54:03 INFO - PROCESS | 1636 | |i|pagename|runs| 10:54:03 INFO - PROCESS | 1636 | |0;dojo.html;2992.850357482126;3347.9832600836994;3352.966470335296;3363.159210197452;3404.5233667286584;5613.859653508664;5679.545636349092;5686.232358631583;5696.658200507962;5710.628809127404;5622.746976386063;5694.874115926743;5702.971485142569;5706.058500347438;5709.371969083408;5191.273221748958;5195.766190521435;5222.9477705223035;5229.3201883755055;5248.763805628756;5131.486851314876;5151.793928242866;5169.793208271683;5189.143791274454;5296.020236256286;5197.8440646780655;5271.736413179345;5284.762185701634;5294.444083371249;5317.149256119022;5274.156135018397;5275.947240527584;5279.260903473498;5280.947190528084;5318.893622127574;5008.574271186953;5125.359330083739;5196.9740151299;5211.452797456251;5220.4779522047875;5608.859778505529;5618.550515958714;5631.380548139701;5632.971835140839;5651.350094739117;6114.021756518936;6132.765133558534;6136.447719705203;6227.1282020517165;6232.220972378453;5221.817236396708;6173.629582225036;6231.252249730016;6256.15541901842;6300.149479820211;5762.855928601818;5763.8559036024435;5780.88438231233;5783.132530120515;5784.074548072373;6172.444479996821;6197.907031394533;6225.470834979077;6225.595336303126;6265.65538895356;6131.110988906673;6134.171886795315;6134.631922084689;6135.815925522242;6148.077788331786;6212.534059945522;6226.844328891723;6256.249250089927;6259.874802503924;6268.843278918063;5988.850278743066;6090.878182436327;6094.512439004869;6118.632882027093;6180.505559555225;6166.660833654102;6224.470919971763;6240.375962403814;6240.469560087444;6241.656708880964;4865.051314993542;4913.87715307113;4922.606191504671;4931.4821943696115;4956.876078098005;4340.175366680321;4383.846565370261;4387.780610969438;4443.355713421601;4455.1535208310315;5628.549716022709;5646.181303711021;5651.152327150878;5699.430056994267;5780.421957804186;6248.565125819739;6262.530310226751;6277.7175027123885;6279.905801412982;6281.152044473937;5707.058335374617;5714.7428365723645;5717.742701578439;5721.141828725641;5756.510696590837;5247.55395791362;5249.737513124329;5296.814611488579;5319.787208511693;5370.248165256867;6279.058141278845;6284.402981716729;6285.811425657238;6286;6324;6223.751049958132;6273.278572964167;6278.121063051085;6289.371062893674;6295.937040629535;6204.534659900616;6237.7193026314835;6260.060990851409;6267.373262673696;6320.841978950563;6077.331493535796;6096.969515152396;6128.958077126988;6184.474319682878;6202.81391558254;6221.782237621662;6252.124702541739;6254.530910181664;6260.718267677874;6261.906071409024;5799.8550036249435;6060;6076.240469941257;6087.969560152171;6090.208272924491;5835.270591176103;5964.671943043174;5968.8806223876145;6012.068129439944;6087.512998959984;5929.6145750526075;5931.555133364928;5938.168656388196;5940.198073260054;5970.104484327386;4992.650514463952;6033.095035744674;6059.303180134165;6116.388361164026;6123.540734445023;4741.905161896674;5768.2501274834;5813.011787995966;5860.912086318624;5872.794452194153 10:54:03 INFO - PROCESS | 1636 | |1;ext.html;450.2593234129856;476.6853876441549;511.6827566908517;521.9451957544458;543.2367523629302;27513;38459.423108653355;39847.99999999996;39992.600073999325;41945.16109677804;41126.7943660282;41640;41691;41719.58280417195;41744.58255417445;8515.872261916076;9326.06739326067;9491.762705932373;9512.286578506604;9518.190953768937;8482.491050536988;11305.378204198765;11571.190016698834;11585.884141158607;11592.130590205727;11258.943705281463;11265.718357041058;11282.605108821193;11323.490442930088;11429.42852857358;6538.444232240266;8671;11362.943185284063;11446.656600302003;11476.88523114775;10964.342139471577;11302;11314.660560183125;11450.885491145149;11485.196036277464;31896.681033189605;39452.21095578101;39495.01262468433;39545.60454395448;39558;3131.2015436063884;3203.1511649412923;3253.430649636304;3486.6339034401276;3497.090756403328;24600.5079898401;29306;29527.11418657422;29648.555271670943;30329.241768955733;3137.5607414961923;3151.68483151683;3191.808491490518;3194.6965038321323;3305.173706573357;498504;499501.5024924852;502001;502782;510927.9999999963;32755.67244327574;33291.83354083214;33678.15804604856;34811;58569.82860342855;34940;35024.64975350214;35061.47407788885;35071;35101.824490877894;14277.92861035709;14390.42438302477;14395;14421.639459013399;14429.7835532465;10004.449755263531;10575.048245657774;11048.944755276172;11278.774424511465;11295.152863535268;7313.049303590499;7343.88984165238;7366.263373662591;7575.166731659513;7794;3225.419424503612;3263.8204898730796;3270.9345813084083;3286.293446908926;3302.2239773653173;891.3003292415423;941.2705153506088;991.3939418142655;992.980140397188;1058.7564860082225;29537.966171183907;30522.779088836643;31411.685883141334;31740.523892141635;31954.520682189323;978.6917121106828;984.3552473130111;1019.3995427179406;1035.3529044347283;1061.4533515239657;14885.851141488663;14904;15112.773308400383;15209.087454752751;15260.31328590216;691267;695123;695326;698916;699262;55971.44028559744;56397.43602564004;56482;56507;56530.15204771932;1243.0366465988932;1292.877176668215;1373.1074801379184;1398.3148257354026;1418.2979425184599;28896.98860539871;29746.256343591584;29948.401031979673;30430;30495;49391.506084938694;51421.74289128531;53946.460535395716;55345.72327138339;58746.4125358758;478460;480774;481446.33491492266;482070;482599.5870020768;26093;26630.733692662827;26908.865455672596;41183.7940810294;41934;42335.576644233166;44853.102937940406;46092.53907461017;46214.76892615516;46421.53578464307;35925.64074359328;37641.247175055796;37660.811695941346;38813.41779873248;39974.80012599919;38094.428583571826;39194.60805392024;39503.60496394999;39630.40554391744;39752;32063.519047214777;36209.45685814768;37463.81268093642;37788;38444.61555384522;32162;33705.65177392877;34351;34437.48343774795;34501.827490862386;25306.987720490975;25419.6187057198;25484.745152548236;25536;25942 10:54:03 INFO - PROCESS | 1636 | |2;jquery.html;330.35415762184925;353.6039635608119;371.637653288044;380.402767654782;399.654299031338;12864.742705145904;12942.964562834974;13662.248576328298;13698.657533561653;16591.751123733153;19352.03239838006;19683.311084112058;19734.210631574755;19866.602667946634;20003.699944500844;5581.107022876341;5948.1969934059;5951.256092988377;5958.642481451105;5965.552583556229;5567.693776842272;5601.907628012519;5618.578606604521;5916.467517923386;5995.550333724967;5568.66588004719;5573.386927437999;5574.804881829137;5577.9163312550145;5579.302587176603;5544.584156188262;5554.055810512223;5624.128260119688;5932.377100404482;5981.371955944606;5775.88448231033;5815.680137592431;5828.446297601721;5828.795992140297;5829.854253643651;12659.620211393672;12955.222686638737;13083.411246493835;13085.084044116915;13103.86896131036;2180.716506854099;2185.3553201805507;2298.494331247123;2359.5634807560486;2758.3242105684035;9201.585928633162;9661.178799801955;9724.222062235074;10060;10129.89870101297;1789.6868048091662;1830.5423644088978;1917.5397904502959;1919.7696276446777;1964.3321270768008;112785;121598.39200804027;124096.75903241032;126250.36874815659;126518.36740816329;11345.149113816497;11548.614166299929;11774.05807535395;11831.526738930346;12081.697957550956;14153.716925661429;14561.344739486749;14606.780898286534;14619.634509137357;14631.780523292158;8434.746957591282;8451.577421129043;8454.830903381899;8531.01893282268;8562.400631955828;8343.749687509384;8366.832663346699;8370.581470926429;8504.957475212708;8514.403991720645;4396.230659634552;4502.122086193226;4505.5719706627815;4583.518730533313;4598.195315819785;1428.6856891483858;1608.6621809420153;1798.4804595683297;1852.1572684428552;1968.3209292793963;788.3181048393213;875.4878396138203;900.2258058070055;932.4079209701888;943.7782121201512;19751.901240493706;20391.85999023821;20741.40732408449;20766.792332076788;21042.684359734616;904.3850181876387;906.7053207707521;936.5738588942015;955.9856602150974;964.2719746591418;9989.700308990741;10240.948795255976;10794.24440289188;10895.83656245157;10941.28881622708;469204;587946;649329;664453.6777316083;667382;92844;144338.27830860778;145396.27301863424;145637.8154327686;147272.52727472803;941.3692825806863;945.4894357047255;1005.8491226316111;1009.9646512372032;1014.1633152649094;15373.923130384277;17074;18949.715754263972;19081.233221182574;19438.222471101;50670;51522;51556.226656600935;52333;53608.195877061095;73841.63079184784;78105.60947195228;78366.2163378359;78367;78472.60763696145;7800.726974555863;7906.723264685708;8064.879026814485;8188.344932405513;8228.876566851382;11734.061275097802;12401.465582308998;12529;12717.300548469922;12907.483700651868;8974.730758077269;10777.62278320255;10778.191635627203;11022.834657479983;11064.059554937598;9486.383385079947;10428.322159059637;10743.488114373522;10761.461926903467;10896.673099807018;11019.779604407706;11275.887241127484;11371.31772093677;11464.770704586028;11545;7688.538687678757;7842.9215707842195;7948.125706172432;7967.362610991223;8369.581520924052;8076.192380761877;10065.245106616887;10312.329698569869;10468.528916198637;10742.83885741699 10:54:03 INFO - PROCESS | 1636 | |3;mootools.html;1447.4427345471993;1464.963375915602;1499.3028241867532;1798.5953160538872;1842.1894366478748;7972.282494575487;8416.83166336673;8418.410711250215;8420.452670576407;8422.11567785383;7365.558066516;7601.77194684159;7614.8857767133395;7985.201479852012;8194.46735962162;2265.026038803318;2303.0557471436714;2307.6077066898583;2308;2309.58427483053;2258.5369999150134;2286.7941885230325;2305.8270629702756;2310.549442858643;2315.6758053872386;2290.0954123121355;2291.942701432461;2318.686977258074;2321.3732292281074;2326.6277395616703;2254.4589298568308;2281.212981521389;2282.8173746100274;2286.668433077211;2287.782660647244;2204.647256438962;2304.0783686525356;2305.3545007397956;2306.5963456395066;2308.9769102309097;8180.631871565734;8258.917410825936;8262;8265;8269.793255168608;4974.701517908937;5144.511271429208;5146.665466744687;5155.097857874857;5172.741362931838;3212.405704944569;3254.1864533866533;3396.6433524480067;3474.9305013899834;3484.7734897231853;1015.096564057989;1045.4929359260752;1058.560697310615;1069.8127827630212;1076.9192310576739;10401.375917444977;10718.035376816124;10725.678229653124;10742.624008159677;10751.978562036673;4249.192653395845;4320.092780516064;4416.447944006999;4451.310046942729;4453.621442177387;8469.32245420365;8471.152884711479;8471.534065626327;8475.279601233964;8499.70251041223;5017.874553136127;5023.748812559357;5029.547340739277;5030.220315851049;5035.521625445577;4110.671146308288;4269.252880745857;4649.163150632919;4726.810927562928;4731.503192164842;3037.4684430224625;3085.7222849943614;3303.141183292313;3692.778433294011;3697.1126929537254;983.2527279267665;1140.0993215359772;1142.6514912951463;1144.6165534546021;1158.988410115888;878.7599830816337;880.264978742757;886.4902680958346;896.4710820615737;910.6311943662827;5344.518993290622;5374.758135883894;5553.861153471114;5606.85982850432;5640.266765320564;963.0562049191691;963.3545524498603;992.8560358747941;996.5664935752826;1024.9795004099876;3603.4774957631;3612.223371975038;3784.545854497423;3784.9810750946067;3789.8673546425744;9680;10113.898861011443;10115.291929564859;10117.19062475015;10187.337823041478;9314.347995640375;9741.659041933499;9742.805143897082;10056.59773609062;10059.748506287255;7471.103467583925;7808.5314881107315;7810.804729881685;7812.57030863308;7816.960915195387;4557.726536407826;5327.174287985368;5402.35171779389;5491.862703432286;5603.859903502445;8936.910630893868;8998.910010899806;9118;9267.351285409957;9303;9100.044495327895;9343.953280233827;9375.812483750424;9494.05059494045;9553.904460955302;6852.931470685428;7100.7869763907165;7528.51064680794;7764.728234511765;7769.145394006542;7322.560646361236;7792.1428642848105;7813.257740514642;7917.485363451357;7931.2068793120225;6928.064711263914;6969.7909062728195;7073.469489788184;7084.2915708428745;7095.716171353088;6848.178218613798;6872.965635171792;6873.175218973755;6940.09778728762;6990.720371185299;6510.837229069311;6554.442872355712;6637.369449902251;6653.068570400244;6663.800085997428;4196.9370459442525;4673;4745.008209443017;4774.140654682191;4792.137415265146;4724.220503616864;4774.737389443614;4891.046245981999;4907.067657145131;4924.4090709115135 10:54:03 INFO - PROCESS | 1636 | |4;prototype.html;302.420864045353;303.4371241347301;311.4938225383752;315.4542641230671;318.2727467736223;5365.275687782148;5444.7822087116565;5466.152746324321;5476.808311709091;5488.6981216033155;4204.831806727727;4665.963941190871;4838.798346752228;4859;5052.368453943257;3421.43546314859;3468.393031219539;3839.790466003214;3873.360895452247;3993.682084911972;4049;4054.776987265699;4055.5538890721996;4065.247929133105;4098.938515922264;3647.270545890819;3897.142628621699;3942.5268967723914;3970.305196590585;3996.3006473867104;3362.663733626642;3372.409828280041;3917.6669983051474;4055.047063939972;4084.5893678778257;3037;3508.5438892944007;3513.1744040150543;3527.94708079379;3537.3809583322814;4878.365812444395;5163.948360516384;5216.973915130438;5218.217267409881;5228.686278823283;3863.4784304118866;4370.497392799805;4456.398386217851;4636.953630463686;4659.953400465985;5551.974174075139;5564.4713752193475;5575.581831362623;5579.69311687857;5613.915791263134;3853.9614603853884;3858.5144719283;3867.284552357823;3868.303705333039;3893.0851249956236;4318.658280922445;5518.570864942338;5573.634459557435;5581.441855814427;5831.737571809236;3850.403187505941;3890.5179864021543;3909.8240579173435;3909.980450097731;3911.7848518331766;3966.781826999543;3983.322835117979;4023.6177563131455;4050.3519436890547;4069.927204960236;4076.3274059779806;4249.553796851348;4539.228331183706;4649.837255696035;4722.574968252874;4574.725516469022;5547.699119024174;5555.805546805843;5567.886422715473;5581.860453488614;1266.461753754669;1280.295837289487;1283.7496688145723;1283.8010108433207;1305.3016636099642;692.4564217089561;831.243568352796;840.7393707950555;855.2601999270586;872.2019352292708;4491.124230775033;4658.790354434059;4814.6870453420415;5065.468125846806;5077.847664570068;5146.202338637517;5288.65623734456;5515.310586176728;5527.39198688144;5529.709822655514;3959.6634286086005;4109.8972525686495;4673.322368256584;4908.797344650512;4986.055438379035;2316.6756654068445;2329.7087864017;2331.836771426018;2633.867437002069;2725.9727402726116;5113.284140220372;5136.815174591777;5238.397584277857;5604.271444712162;5659;4516;4737.957649317144;5124.5131712487255;5313;5592.748326325325;4614.423197100363;5142.97428512855;5147.408048074444;5585.497305242467;5636.026601246112;4456.148065238315;5398.163284690879;5403.459654034565;5442.884208737279;5449.6730196188355;4972.65191436596;5065.873353166053;5093.719845408538;5314.893702125859;5456.890862182813;4932.4081110267;5141.254518094781;5599;5610.803621873215;5646.576506762091;3542.1321776165005;3622.6558476944647;3628.219932714479;3659.341318562684;3701.8704345347783;4818.63913158592;4900;5286.0705842368025;5302.840914772563;5302.840914772563;4429.604674527513;4465.96314506126;5131.692098474104;5225.346831646044;5243.217306115786;4593.058423023339;4657.090592856913;4837.903241935212;4960.355153829979;5216.869578260574;4815.975920120377;5099.974500127476;5114.800635726525;5130.48695130484;5255.13290307095;4818.855434336975;5084.7966081356335;5251.715503755879;5526.640768350045;5576.944230557642;4485;4781.784819683053;4902.803887844446;5559.4982257984875;5594.608377413542 10:54:03 INFO - PROCESS | 1636 | |5;yui.html;692.7090621938783;697.9441644668427;764.5947647746697;779.4699604269094;789.885466607342;13853.861461385382;14039.859601403983;14077.873770098393;14085.366158522866;14109.082909610881;12861.035422343315;12967.157134786232;12975.416106275217;12977.74044519109;12984.870151298484;619.4301242856583;646.0954663471155;794.3565711773482;818.4148333941231;820.0159808230147;780.3562061299423;826.7726375246795;835.8913341265627;837.8952630921135;843.0978852627691;741.7292688168807;882.2589025218815;883.7039591736743;889.5952341684518;890.4034297021011;657.7270432770395;682.8258794007546;882.9426087304337;882.9514376709278;884.0143240287098;507.6877720202086;508.8269988203991;510.8620672418463;613.9109829074804;634.2547506679633;13693.589192324247;13717.382717777624;13742;13743.244121573309;13747.450101995908;12672.746545069047;12707.618771436872;12777.808332875014;12858.614241572768;12982.935085324607;11630.592929247436;11857.525698972031;11886.320394313016;11936.28382297065;11983.400829958555;4062.1875624874847;4100.36444351126;4125.814338354781;4346.15250026245;4388.188185185751;84421;89055.55472222598;91436;93681.59477607842;96417.55373669471;10130.645427410152;10138.239632027628;10373.740656483647;10433.2696711231;10522.210834187466;19618.11718472672;19702.113404896812;19734.506637334183;19753;19771.308004219783;6344.904826427607;6988;7170.533915295489;7183.138023437221;7556.848863022708;4068.267711811843;4092.2633925893624;4099.508059032935;4115.279826030433;4115.506139263308;2676.9196924092307;3070.2784845561273;3180.189051791778;3195.6165260168927;3208.53476245943;598.6408155106988;608.7534548507773;614.1770028162284;616.5221952986471;616.5600304816242;560.2772423573545;585.1164741240757;592.4963780786297;597.5548216578677;604.7853012180612;6672.933270667231;8127.796805079802;8135.877961830577;8151.5109093454585;8511.829763404821;574.873527823878;591.1635036423498;596.8090211132396;603.0411645483701;606.6178307666142;4057.797110144481;4172.895677608084;4208.894777630584;4233;4293.162833247487;72025.6398718003;72360.63819680868;97557.56106097404;102755;108502;100198.49900750449;101249.49375253076;103641.48179259055;104432.95567044384;104534;87582;88119;88123;90549.54725226332;91797;8058.274755272054;8205.671773129008;8249.711260105867;8309.293710034715;8384.118928207465;39613.811585652475;48735;56381.71809141092;57588;58665.70667146637;57436;69134.65432673006;71496.5700685973;73167.63416182886;77357.22642773726;11900.702482438008;12069.57756478519;12146.331951742739;12209.067570598654;12643.431045603154;12289.324087175291;12339.69150771202;13091.214527128403;13649.454021839412;13995.300234988006;10905;12666.683332916382;13322.800157997832;13358.265295408846;13603.251821149932;8740.956295218482;12459.252444853339;12714.554990575285;12719.300438475602;13440.46238150463;12475;12619.873801262238;12649.683757905757;12897.935510322388;12946.870531294944;10774.784504310026;10803.243772936134;11091.33451992883;11178.664640060812;11492.770144596894;11209.607663731731;11215.60745373908;11407.942960285145;11415.429228538374;11441.828372574251 10:54:03 INFO - PROCESS | 1636 | __end_tp_report 10:54:03 INFO - PROCESS | 1636 | __start_cc_report 10:54:03 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,1573 10:54:03 INFO - PROCESS | 1636 | __end_cc_report 10:54:03 INFO - PROCESS | 1636 | __startTimestamp1480445643757__endTimestamp 10:54:03 INFO - PROCESS | 1636 | 10:54:03 INFO - PROCESS | 1636 | ------- Summary: start ------- 10:54:03 INFO - PROCESS | 1636 | Number of tests: 6 10:54:03 INFO - PROCESS | 1636 | 10:54:03 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5703.50 Median:5860.91 stddev:644.94 (11.0%) stddev-sans-first:610.85 10:54:03 INFO - PROCESS | 1636 | Values: 2992.9 3348.0 3353.0 3363.2 3404.5 5613.9 5679.5 5686.2 5696.7 5710.6 5622.7 5694.9 5703.0 5706.1 5709.4 5191.3 5195.8 5222.9 5229.3 5248.8 5131.5 5151.8 5169.8 5189.1 5296.0 5197.8 5271.7 5284.8 5294.4 5317.1 5274.2 5275.9 5279.3 5280.9 5318.9 5008.6 5125.4 5197.0 5211.5 5220.5 5608.9 5618.6 5631.4 5633.0 5651.4 6114.0 6132.8 6136.4 6227.1 6232.2 5221.8 6173.6 6231.3 6256.2 6300.1 5762.9 5763.9 5780.9 5783.1 5784.1 6172.4 6197.9 6225.5 6225.6 6265.7 6131.1 6134.2 6134.6 6135.8 6148.1 6212.5 6226.8 6256.2 6259.9 6268.8 5988.9 6090.9 6094.5 6118.6 6180.5 6166.7 6224.5 6240.4 6240.5 6241.7 4865.1 4913.9 4922.6 4931.5 4956.9 4340.2 4383.8 4387.8 4443.4 4455.2 5628.5 5646.2 5651.2 5699.4 5780.4 6248.6 6262.5 6277.7 6279.9 6281.2 5707.1 5714.7 5717.7 5721.1 5756.5 5247.6 5249.7 5296.8 5319.8 5370.2 6279.1 6284.4 6285.8 6286.0 6324.0 6223.8 6273.3 6278.1 6289.4 6295.9 6204.5 6237.7 6260.1 6267.4 6320.8 6077.3 6097.0 6129.0 6184.5 6202.8 6221.8 6252.1 6254.5 6260.7 6261.9 5799.9 6060.0 6076.2 6088.0 6090.2 5835.3 5964.7 5968.9 6012.1 6087.5 5929.6 5931.6 5938.2 5940.2 5970.1 4992.7 6033.1 6059.3 6116.4 6123.5 4741.9 5768.3 5813.0 5860.9 5872.8 10:54:03 INFO - PROCESS | 1636 | 10:54:03 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:67643.59 Median:19218.55 stddev:152314.91 (792.5%) stddev-sans-first:152658.64 10:54:03 INFO - PROCESS | 1636 | Values: 450.3 476.7 511.7 521.9 543.2 27513.0 38459.4 39848.0 39992.6 41945.2 41126.8 41640.0 41691.0 41719.6 41744.6 8515.9 9326.1 9491.8 9512.3 9518.2 8482.5 11305.4 11571.2 11585.9 11592.1 11258.9 11265.7 11282.6 11323.5 11429.4 6538.4 8671.0 11362.9 11446.7 11476.9 10964.3 11302.0 11314.7 11450.9 11485.2 31896.7 39452.2 39495.0 39545.6 39558.0 3131.2 3203.2 3253.4 3486.6 3497.1 24600.5 29306.0 29527.1 29648.6 30329.2 3137.6 3151.7 3191.8 3194.7 3305.2 498504.0 499501.5 502001.0 502782.0 510928.0 32755.7 33291.8 33678.2 34811.0 58569.8 34940.0 35024.6 35061.5 35071.0 35101.8 14277.9 14390.4 14395.0 14421.6 14429.8 10004.4 10575.0 11048.9 11278.8 11295.2 7313.0 7343.9 7366.3 7575.2 7794.0 3225.4 3263.8 3270.9 3286.3 3302.2 891.3 941.3 991.4 993.0 1058.8 29538.0 30522.8 31411.7 31740.5 31954.5 978.7 984.4 1019.4 1035.4 1061.5 14885.9 14904.0 15112.8 15209.1 15260.3 691267.0 695123.0 695326.0 698916.0 699262.0 55971.4 56397.4 56482.0 56507.0 56530.2 1243.0 1292.9 1373.1 1398.3 1418.3 28897.0 29746.3 29948.4 30430.0 30495.0 49391.5 51421.7 53946.5 55345.7 58746.4 478460.0 480774.0 481446.3 482070.0 482599.6 26093.0 26630.7 26908.9 41183.8 41934.0 42335.6 44853.1 46092.5 46214.8 46421.5 35925.6 37641.2 37660.8 38813.4 39974.8 38094.4 39194.6 39503.6 39630.4 39752.0 32063.5 36209.5 37463.8 37788.0 38444.6 32162.0 33705.7 34351.0 34437.5 34501.8 25307.0 25419.6 25484.7 25536.0 25942.0 10:54:03 INFO - PROCESS | 1636 | 10:54:03 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:34892.75 Median:341.98 stddev:102556.91 (29989.2%) stddev-sans-first:102811.78 10:54:03 INFO - PROCESS | 1636 | Values: 330.4 353.6 371.6 380.4 399.7 12864.7 12943.0 13662.2 13698.7 16591.8 19352.0 19683.3 19734.2 19866.6 20003.7 5581.1 5948.2 5951.3 5958.6 5965.6 5567.7 5601.9 5618.6 5916.5 5995.6 5568.7 5573.4 5574.8 5577.9 5579.3 5544.6 5554.1 5624.1 5932.4 5981.4 5775.9 5815.7 5828.4 5828.8 5829.9 12659.6 12955.2 13083.4 13085.1 13103.9 2180.7 2185.4 2298.5 2359.6 2758.3 9201.6 9661.2 9724.2 10060.0 10129.9 1789.7 1830.5 1917.5 1919.8 1964.3 112785.0 121598.4 124096.8 126250.4 126518.4 11345.1 11548.6 11774.1 11831.5 12081.7 14153.7 14561.3 14606.8 14619.6 14631.8 8434.7 8451.6 8454.8 8531.0 8562.4 8343.7 8366.8 8370.6 8505.0 8514.4 4396.2 4502.1 4505.6 4583.5 4598.2 1428.7 1608.7 1798.5 1852.2 1968.3 788.3 875.5 900.2 932.4 943.8 19751.9 20391.9 20741.4 20766.8 21042.7 904.4 906.7 936.6 956.0 964.3 9989.7 10240.9 10794.2 10895.8 10941.3 469204.0 587946.0 649329.0 664453.7 667382.0 92844.0 144338.3 145396.3 145637.8 147272.5 941.4 945.5 1005.8 1010.0 1014.2 15373.9 17074.0 18949.7 19081.2 19438.2 50670.0 51522.0 51556.2 52333.0 53608.2 73841.6 78105.6 78366.2 78367.0 78472.6 7800.7 7906.7 8064.9 8188.3 8228.9 11734.1 12401.5 12529.0 12717.3 12907.5 8974.7 10777.6 10778.2 11022.8 11064.1 9486.4 10428.3 10743.5 10761.5 10896.7 11019.8 11275.9 11371.3 11464.8 11545.0 7688.5 7842.9 7948.1 7967.4 8369.6 8076.2 10065.2 10312.3 10468.5 10742.8 10:54:03 INFO - PROCESS | 1636 | 10:54:03 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5335.87 Median:5029.88 stddev:2923.07 (58.1%) stddev-sans-first:2916.66 10:54:03 INFO - PROCESS | 1636 | Values: 1447.4 1465.0 1499.3 1798.6 1842.2 7972.3 8416.8 8418.4 8420.5 8422.1 7365.6 7601.8 7614.9 7985.2 8194.5 2265.0 2303.1 2307.6 2308.0 2309.6 2258.5 2286.8 2305.8 2310.5 2315.7 2290.1 2291.9 2318.7 2321.4 2326.6 2254.5 2281.2 2282.8 2286.7 2287.8 2204.6 2304.1 2305.4 2306.6 2309.0 8180.6 8258.9 8262.0 8265.0 8269.8 4974.7 5144.5 5146.7 5155.1 5172.7 3212.4 3254.2 3396.6 3474.9 3484.8 1015.1 1045.5 1058.6 1069.8 1076.9 10401.4 10718.0 10725.7 10742.6 10752.0 4249.2 4320.1 4416.4 4451.3 4453.6 8469.3 8471.2 8471.5 8475.3 8499.7 5017.9 5023.7 5029.5 5030.2 5035.5 4110.7 4269.3 4649.2 4726.8 4731.5 3037.5 3085.7 3303.1 3692.8 3697.1 983.3 1140.1 1142.7 1144.6 1159.0 878.8 880.3 886.5 896.5 910.6 5344.5 5374.8 5553.9 5606.9 5640.3 963.1 963.4 992.9 996.6 1025.0 3603.5 3612.2 3784.5 3785.0 3789.9 9680.0 10113.9 10115.3 10117.2 10187.3 9314.3 9741.7 9742.8 10056.6 10059.7 7471.1 7808.5 7810.8 7812.6 7817.0 4557.7 5327.2 5402.4 5491.9 5603.9 8936.9 8998.9 9118.0 9267.4 9303.0 9100.0 9344.0 9375.8 9494.1 9553.9 6852.9 7100.8 7528.5 7764.7 7769.1 7322.6 7792.1 7813.3 7917.5 7931.2 6928.1 6969.8 7073.5 7084.3 7095.7 6848.2 6873.0 6873.2 6940.1 6990.7 6510.8 6554.4 6637.4 6653.1 6663.8 4196.9 4673.0 4745.0 4774.1 4792.1 4724.2 4774.7 4891.0 4907.1 4924.4 10:54:03 INFO - PROCESS | 1636 | 10:54:03 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4335.30 Median:4817.31 stddev:1313.32 (27.3%) stddev-sans-first:1281.65 10:54:03 INFO - PROCESS | 1636 | Values: 302.4 303.4 311.5 315.5 318.3 5365.3 5444.8 5466.2 5476.8 5488.7 4204.8 4666.0 4838.8 4859.0 5052.4 3421.4 3468.4 3839.8 3873.4 3993.7 4049.0 4054.8 4055.6 4065.2 4098.9 3647.3 3897.1 3942.5 3970.3 3996.3 3362.7 3372.4 3917.7 4055.0 4084.6 3037.0 3508.5 3513.2 3527.9 3537.4 4878.4 5163.9 5217.0 5218.2 5228.7 3863.5 4370.5 4456.4 4637.0 4660.0 5552.0 5564.5 5575.6 5579.7 5613.9 3854.0 3858.5 3867.3 3868.3 3893.1 4318.7 5518.6 5573.6 5581.4 5831.7 3850.4 3890.5 3909.8 3910.0 3911.8 3966.8 3983.3 4023.6 4050.4 4069.9 4076.3 4249.6 4539.2 4649.8 4722.6 4574.7 5547.7 5555.8 5567.9 5581.9 1266.5 1280.3 1283.7 1283.8 1305.3 692.5 831.2 840.7 855.3 872.2 4491.1 4658.8 4814.7 5065.5 5077.8 5146.2 5288.7 5515.3 5527.4 5529.7 3959.7 4109.9 4673.3 4908.8 4986.1 2316.7 2329.7 2331.8 2633.9 2726.0 5113.3 5136.8 5238.4 5604.3 5659.0 4516.0 4738.0 5124.5 5313.0 5592.7 4614.4 5143.0 5147.4 5585.5 5636.0 4456.1 5398.2 5403.5 5442.9 5449.7 4972.7 5065.9 5093.7 5314.9 5456.9 4932.4 5141.3 5599.0 5610.8 5646.6 3542.1 3622.7 3628.2 3659.3 3701.9 4818.6 4900.0 5286.1 5302.8 5302.8 4429.6 4466.0 5131.7 5225.3 5243.2 4593.1 4657.1 4837.9 4960.4 5216.9 4816.0 5100.0 5114.8 5130.5 5255.1 4818.9 5084.8 5251.7 5526.6 5576.9 4485.0 4781.8 4902.8 5559.5 5594.6 10:54:03 INFO - PROCESS | 1636 | 10:54:03 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:20035.43 Median:4149.36 stddev:29505.39 (711.1%) stddev-sans-first:29552.42 10:54:03 INFO - PROCESS | 1636 | Values: 692.7 697.9 764.6 779.5 789.9 13853.9 14039.9 14077.9 14085.4 14109.1 12861.0 12967.2 12975.4 12977.7 12984.9 619.4 646.1 794.4 818.4 820.0 780.4 826.8 835.9 837.9 843.1 741.7 882.3 883.7 889.6 890.4 657.7 682.8 882.9 883.0 884.0 507.7 508.8 510.9 613.9 634.3 13693.6 13717.4 13742.0 13743.2 13747.5 12672.7 12707.6 12777.8 12858.6 12982.9 11630.6 11857.5 11886.3 11936.3 11983.4 4062.2 4100.4 4125.8 4346.2 4388.2 84421.0 89055.6 91436.0 93681.6 96417.6 10130.6 10138.2 10373.7 10433.3 10522.2 19618.1 19702.1 19734.5 19753.0 19771.3 6344.9 6988.0 7170.5 7183.1 7556.8 4068.3 4092.3 4099.5 4115.3 4115.5 2676.9 3070.3 3180.2 3195.6 3208.5 598.6 608.8 614.2 616.5 616.6 560.3 585.1 592.5 597.6 604.8 6672.9 8127.8 8135.9 8151.5 8511.8 574.9 591.2 596.8 603.0 606.6 4057.8 4172.9 4208.9 4233.0 4293.2 72025.6 72360.6 97557.6 102755.0 108502.0 100198.5 101249.5 103641.5 104433.0 104534.0 87582.0 88119.0 88123.0 90549.5 91797.0 8058.3 8205.7 8249.7 8309.3 8384.1 39613.8 48735.0 56381.7 57588.0 58665.7 57436.0 69134.7 71496.6 73167.6 77357.2 11900.7 12069.6 12146.3 12209.1 12643.4 12289.3 12339.7 13091.2 13649.5 13995.3 10905.0 12666.7 13322.8 13358.3 13603.3 8741.0 12459.3 12714.6 12719.3 13440.5 12475.0 12619.9 12649.7 12897.9 12946.9 10774.8 10803.2 11091.3 11178.7 11492.8 11209.6 11215.6 11407.9 11415.4 11441.8 10:54:03 INFO - PROCESS | 1636 | -------- Summary: end -------- 10:54:03 INFO - PROCESS | 1636 | 10:54:04 INFO - TEST-INFO | 1636: exit 0 10:54:04 INFO - TEST-OK | dromaeo_css | took 1119869ms 10:54:04 INFO - TEST-START | kraken 10:54:04 INFO - operating with platform_type : mac_ 10:54:04 INFO - Initialising browser for kraken test... 10:54:04 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpM2sm2V/profile http://localhost:49236/getInfo.html) 10:54:07 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 10:54:07 INFO - PROCESS | 1646 | colorDepth:24 10:54:07 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 10:54:07 INFO - PROCESS | 1646 | __metrics 10:54:07 INFO - PROCESS | 1646 | 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:54:07 INFO - PROCESS | 1646 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:54:07 INFO - TEST-INFO | 1646: exit 0 10:54:07 INFO - Browser initialized. 10:54:07 INFO - Running cycle 1/1 for kraken test... 10:54:07 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpM2sm2V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:54:20 INFO - PROCESS | 1647 | RSS: Main: 223395840 10:54:20 INFO - PROCESS | 1647 | 10:54:23 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 10:54:24 INFO - PROCESS | 1647 | RSS: Main: 274223104 10:54:24 INFO - PROCESS | 1647 | 10:54:26 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 10:54:27 INFO - PROCESS | 1647 | RSS: Main: 285286400 10:54:27 INFO - PROCESS | 1647 | 10:54:30 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 10:54:31 INFO - PROCESS | 1647 | RSS: Main: 379924480 10:54:31 INFO - PROCESS | 1647 | 10:54:33 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 10:54:33 INFO - PROCESS | 1647 | RSS: Main: 281505792 10:54:33 INFO - PROCESS | 1647 | 10:54:35 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:54:36 INFO - PROCESS | 1647 | RSS: Main: 282501120 10:54:36 INFO - PROCESS | 1647 | 10:54:38 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 10:54:39 INFO - PROCESS | 1647 | RSS: Main: 322252800 10:54:39 INFO - PROCESS | 1647 | 10:54:42 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 10:54:43 INFO - PROCESS | 1647 | RSS: Main: 325947392 10:54:43 INFO - PROCESS | 1647 | 10:54:45 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 10:54:46 INFO - PROCESS | 1647 | RSS: Main: 322891776 10:54:46 INFO - PROCESS | 1647 | 10:54:48 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:54:48 INFO - PROCESS | 1647 | RSS: Main: 299966464 10:54:48 INFO - PROCESS | 1647 | 10:54:50 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:54:51 INFO - PROCESS | 1647 | RSS: Main: 303984640 10:54:51 INFO - PROCESS | 1647 | 10:54:53 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:54:54 INFO - PROCESS | 1647 | RSS: Main: 306491392 10:54:54 INFO - PROCESS | 1647 | 10:54:56 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:54:57 INFO - PROCESS | 1647 | RSS: Main: 322605056 10:54:57 INFO - PROCESS | 1647 | 10:55:00 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:55:01 INFO - PROCESS | 1647 | RSS: Main: 316313600 10:55:01 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 10:55:03 INFO - PROCESS | 1647 | RSS: Main: 360722432 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | __start_tp_report 10:55:03 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 10:55:03 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 10:55:03 INFO - PROCESS | 1647 | |i|pagename|runs| 10:55:03 INFO - PROCESS | 1647 | |0;ai-astar;115;162;169;114;113;113;112;113;113;114 10:55:03 INFO - PROCESS | 1647 | |1;audio-beat-detection;151;113;114;146;114;114;122;142;179;114 10:55:03 INFO - PROCESS | 1647 | |2;audio-dft;183;185;183;185;182;184;184;182;183;184 10:55:03 INFO - PROCESS | 1647 | |3;audio-fft;80;59;59;89;62;59;59;59;83;59 10:55:03 INFO - PROCESS | 1647 | |4;audio-oscillator;85;69;95;70;68;70;89;67;69;92 10:55:03 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;87;83;86;84;86;83;86;84;87;84 10:55:03 INFO - PROCESS | 1647 | |6;imaging-darkroom;92;93;93;91;93;92;94;93;92;92 10:55:03 INFO - PROCESS | 1647 | |7;imaging-desaturate;96;129;131;82;84;80;83;83;83;83 10:55:03 INFO - PROCESS | 1647 | |8;json-parse-financial;56;57;54;55;57;58;56;58;58;57 10:55:03 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;66;74;67;75;72;70;69;71;72;72 10:55:03 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;86;85;86;86;86;86;82;83;79;83 10:55:03 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;148;147;149;148;149;152;166;153;197;176 10:55:03 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;169;170;168;168;168;168;159;204;224;187 10:55:03 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;68;68;67;66;67;66;67;65;80;81 10:55:03 INFO - PROCESS | 1647 | __end_tp_report 10:55:03 INFO - PROCESS | 1647 | __start_cc_report 10:55:03 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,2118 10:55:03 INFO - PROCESS | 1647 | __end_cc_report 10:55:03 INFO - PROCESS | 1647 | __startTimestamp1480445703511__endTimestamp 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | ------- Summary: start ------- 10:55:03 INFO - PROCESS | 1647 | Number of tests: 14 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:123.80 Median:114.00 stddev:22.05 (19.3%) stddev-sans-first:23.16 10:55:03 INFO - PROCESS | 1647 | Values: 115.0 162.0 169.0 114.0 113.0 113.0 112.0 113.0 113.0 114.0 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:132.00 stddev:22.63 (17.1%) stddev-sans-first:22.81 10:55:03 INFO - PROCESS | 1647 | Values: 151.0 113.0 114.0 146.0 114.0 114.0 122.0 142.0 179.0 114.0 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.08 (0.6%) stddev-sans-first:1.13 10:55:03 INFO - PROCESS | 1647 | Values: 183.0 185.0 183.0 185.0 182.0 184.0 184.0 182.0 183.0 184.0 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:66.80 Median:60.50 stddev:12.10 (20.0%) stddev-sans-first:11.85 10:55:03 INFO - PROCESS | 1647 | Values: 80.0 59.0 59.0 89.0 62.0 59.0 59.0 59.0 83.0 59.0 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:77.50 stddev:11.36 (14.7%) stddev-sans-first:11.72 10:55:03 INFO - PROCESS | 1647 | Values: 85.0 69.0 95.0 70.0 68.0 70.0 89.0 67.0 69.0 92.0 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:85.00 Median:86.00 stddev:1.56 (1.8%) stddev-sans-first:1.48 10:55:03 INFO - PROCESS | 1647 | Values: 87.0 83.0 86.0 84.0 86.0 83.0 86.0 84.0 87.0 84.0 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:93.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 10:55:03 INFO - PROCESS | 1647 | Values: 92.0 93.0 93.0 91.0 93.0 92.0 94.0 93.0 92.0 92.0 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:93.40 Median:83.00 stddev:19.77 (23.8%) stddev-sans-first:20.95 10:55:03 INFO - PROCESS | 1647 | Values: 96.0 129.0 131.0 82.0 84.0 80.0 83.0 83.0 83.0 83.0 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:1.35 (2.4%) stddev-sans-first:1.41 10:55:03 INFO - PROCESS | 1647 | Values: 56.0 57.0 54.0 55.0 57.0 58.0 56.0 58.0 58.0 57.0 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:72.00 stddev:2.86 (4.0%) stddev-sans-first:2.45 10:55:03 INFO - PROCESS | 1647 | Values: 66.0 74.0 67.0 75.0 72.0 70.0 69.0 71.0 72.0 72.0 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:86.00 stddev:2.39 (2.8%) stddev-sans-first:2.45 10:55:03 INFO - PROCESS | 1647 | Values: 86.0 85.0 86.0 86.0 86.0 86.0 82.0 83.0 79.0 83.0 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:158.50 Median:152.50 stddev:16.50 (10.8%) stddev-sans-first:17.06 10:55:03 INFO - PROCESS | 1647 | Values: 148.0 147.0 149.0 148.0 149.0 152.0 166.0 153.0 197.0 176.0 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.50 Median:169.50 stddev:20.48 (12.1%) stddev-sans-first:21.44 10:55:03 INFO - PROCESS | 1647 | Values: 169.0 170.0 168.0 168.0 168.0 168.0 159.0 204.0 224.0 187.0 10:55:03 INFO - PROCESS | 1647 | 10:55:03 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:67.50 stddev:5.87 (8.7%) stddev-sans-first:6.20 10:55:03 INFO - PROCESS | 1647 | Values: 68.0 68.0 67.0 66.0 67.0 66.0 67.0 65.0 80.0 81.0 10:55:03 INFO - PROCESS | 1647 | -------- Summary: end -------- 10:55:03 INFO - PROCESS | 1647 | 10:55:04 INFO - TEST-INFO | 1647: exit 0 10:55:04 INFO - TEST-OK | kraken | took 60252ms 10:55:04 INFO - SUITE-END | took 1180s 10:55:04 INFO - Completed test suite (00:19:40) 10:55:04 INFO - javascript benchmark 10:55:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2992.850357482126, 3347.9832600836994, 3352.966470335296, 3363.159210197452, 3404.5233667286584, 5613.859653508664, 5679.545636349092, 5686.232358631583, 5696.658200507962, 5710.628809127404, 5622.746976386063, 5694.874115926743, 5702.971485142569, 5706.058500347438, 5709.371969083408, 5191.273221748958, 5195.766190521435, 5222.9477705223035, 5229.3201883755055, 5248.763805628756, 5131.486851314876, 5151.793928242866, 5169.793208271683, 5189.143791274454, 5296.020236256286, 5197.8440646780655, 5271.736413179345, 5284.762185701634, 5294.444083371249, 5317.149256119022, 5274.156135018397, 5275.947240527584, 5279.260903473498, 5280.947190528084, 5318.893622127574, 5008.574271186953, 5125.359330083739, 5196.9740151299, 5211.452797456251, 5220.4779522047875, 5608.859778505529, 5618.550515958714, 5631.380548139701, 5632.971835140839, 5651.350094739117, 6114.021756518936, 6132.765133558534, 6136.447719705203, 6227.1282020517165, 6232.220972378453, 5221.817236396708, 6173.629582225036, 6231.252249730016, 6256.15541901842, 6300.149479820211, 5762.855928601818, 5763.8559036024435, 5780.88438231233, 5783.132530120515, 5784.074548072373, 6172.444479996821, 6197.907031394533, 6225.470834979077, 6225.595336303126, 6265.65538895356, 6131.110988906673, 6134.171886795315, 6134.631922084689, 6135.815925522242, 6148.077788331786, 6212.534059945522, 6226.844328891723, 6256.249250089927, 6259.874802503924, 6268.843278918063, 5988.850278743066, 6090.878182436327, 6094.512439004869, 6118.632882027093, 6180.505559555225, 6166.660833654102, 6224.470919971763, 6240.375962403814, 6240.469560087444, 6241.656708880964, 4865.051314993542, 4913.87715307113, 4922.606191504671, 4931.4821943696115, 4956.876078098005, 4340.175366680321, 4383.846565370261, 4387.780610969438, 4443.355713421601, 4455.1535208310315, 5628.549716022709, 5646.181303711021, 5651.152327150878, 5699.430056994267, 5780.421957804186, 6248.565125819739, 6262.530310226751, 6277.7175027123885, 6279.905801412982, 6281.152044473937, 5707.058335374617, 5714.7428365723645, 5717.742701578439, 5721.141828725641, 5756.510696590837, 5247.55395791362, 5249.737513124329, 5296.814611488579, 5319.787208511693, 5370.248165256867, 6279.058141278845, 6284.402981716729, 6285.811425657238, 6286.0, 6324.0, 6223.751049958132, 6273.278572964167, 6278.121063051085, 6289.371062893674, 6295.937040629535, 6204.534659900616, 6237.7193026314835, 6260.060990851409, 6267.373262673696, 6320.841978950563, 6077.331493535796, 6096.969515152396, 6128.958077126988, 6184.474319682878, 6202.81391558254, 6221.782237621662, 6252.124702541739, 6254.530910181664, 6260.718267677874, 6261.906071409024, 5799.8550036249435, 6060.0, 6076.240469941257, 6087.969560152171, 6090.208272924491, 5835.270591176103, 5964.671943043174, 5968.8806223876145, 6012.068129439944, 6087.512998959984, 5929.6145750526075, 5931.555133364928, 5938.168656388196, 5940.198073260054, 5970.104484327386, 4992.650514463952, 6033.095035744674, 6059.303180134165, 6116.388361164026, 6123.540734445023, 4741.905161896674, 5768.2501274834, 5813.011787995966, 5860.912086318624, 5872.794452194153], "value": 5661.934773266646, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.2593234129856, 476.6853876441549, 511.6827566908517, 521.9451957544458, 543.2367523629302, 27513.0, 38459.423108653355, 39847.99999999996, 39992.600073999325, 41945.16109677804, 41126.7943660282, 41640.0, 41691.0, 41719.58280417195, 41744.58255417445, 8515.872261916076, 9326.06739326067, 9491.762705932373, 9512.286578506604, 9518.190953768937, 8482.491050536988, 11305.378204198765, 11571.190016698834, 11585.884141158607, 11592.130590205727, 11258.943705281463, 11265.718357041058, 11282.605108821193, 11323.490442930088, 11429.42852857358, 6538.444232240266, 8671.0, 11362.943185284063, 11446.656600302003, 11476.88523114775, 10964.342139471577, 11302.0, 11314.660560183125, 11450.885491145149, 11485.196036277464, 31896.681033189605, 39452.21095578101, 39495.01262468433, 39545.60454395448, 39558.0, 3131.2015436063884, 3203.1511649412923, 3253.430649636304, 3486.6339034401276, 3497.090756403328, 24600.5079898401, 29306.0, 29527.11418657422, 29648.555271670943, 30329.241768955733, 3137.5607414961923, 3151.68483151683, 3191.808491490518, 3194.6965038321323, 3305.173706573357, 498504.0, 499501.5024924852, 502001.0, 502782.0, 510927.9999999963, 32755.67244327574, 33291.83354083214, 33678.15804604856, 34811.0, 58569.82860342855, 34940.0, 35024.64975350214, 35061.47407788885, 35071.0, 35101.824490877894, 14277.92861035709, 14390.42438302477, 14395.0, 14421.639459013399, 14429.7835532465, 10004.449755263531, 10575.048245657774, 11048.944755276172, 11278.774424511465, 11295.152863535268, 7313.049303590499, 7343.88984165238, 7366.263373662591, 7575.166731659513, 7794.0, 3225.419424503612, 3263.8204898730796, 3270.9345813084083, 3286.293446908926, 3302.2239773653173, 891.3003292415423, 941.2705153506088, 991.3939418142655, 992.980140397188, 1058.7564860082225, 29537.966171183907, 30522.779088836643, 31411.685883141334, 31740.523892141635, 31954.520682189323, 978.6917121106828, 984.3552473130111, 1019.3995427179406, 1035.3529044347283, 1061.4533515239657, 14885.851141488663, 14904.0, 15112.773308400383, 15209.087454752751, 15260.31328590216, 691267.0, 695123.0, 695326.0, 698916.0, 699262.0, 55971.44028559744, 56397.43602564004, 56482.0, 56507.0, 56530.15204771932, 1243.0366465988932, 1292.877176668215, 1373.1074801379184, 1398.3148257354026, 1418.2979425184599, 28896.98860539871, 29746.256343591584, 29948.401031979673, 30430.0, 30495.0, 49391.506084938694, 51421.74289128531, 53946.460535395716, 55345.72327138339, 58746.4125358758, 478460.0, 480774.0, 481446.33491492266, 482070.0, 482599.5870020768, 26093.0, 26630.733692662827, 26908.865455672596, 41183.7940810294, 41934.0, 42335.576644233166, 44853.102937940406, 46092.53907461017, 46214.76892615516, 46421.53578464307, 35925.64074359328, 37641.247175055796, 37660.811695941346, 38813.41779873248, 39974.80012599919, 38094.428583571826, 39194.60805392024, 39503.60496394999, 39630.40554391744, 39752.0, 32063.519047214777, 36209.45685814768, 37463.81268093642, 37788.0, 38444.61555384522, 32162.0, 33705.65177392877, 34351.0, 34437.48343774795, 34501.827490862386, 25306.987720490975, 25419.6187057198, 25484.745152548236, 25536.0, 25942.0], "value": 18471.862074999495, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.35415762184925, 353.6039635608119, 371.637653288044, 380.402767654782, 399.654299031338, 12864.742705145904, 12942.964562834974, 13662.248576328298, 13698.657533561653, 16591.751123733153, 19352.03239838006, 19683.311084112058, 19734.210631574755, 19866.602667946634, 20003.699944500844, 5581.107022876341, 5948.1969934059, 5951.256092988377, 5958.642481451105, 5965.552583556229, 5567.693776842272, 5601.907628012519, 5618.578606604521, 5916.467517923386, 5995.550333724967, 5568.66588004719, 5573.386927437999, 5574.804881829137, 5577.9163312550145, 5579.302587176603, 5544.584156188262, 5554.055810512223, 5624.128260119688, 5932.377100404482, 5981.371955944606, 5775.88448231033, 5815.680137592431, 5828.446297601721, 5828.795992140297, 5829.854253643651, 12659.620211393672, 12955.222686638737, 13083.411246493835, 13085.084044116915, 13103.86896131036, 2180.716506854099, 2185.3553201805507, 2298.494331247123, 2359.5634807560486, 2758.3242105684035, 9201.585928633162, 9661.178799801955, 9724.222062235074, 10060.0, 10129.89870101297, 1789.6868048091662, 1830.5423644088978, 1917.5397904502959, 1919.7696276446777, 1964.3321270768008, 112785.0, 121598.39200804027, 124096.75903241032, 126250.36874815659, 126518.36740816329, 11345.149113816497, 11548.614166299929, 11774.05807535395, 11831.526738930346, 12081.697957550956, 14153.716925661429, 14561.344739486749, 14606.780898286534, 14619.634509137357, 14631.780523292158, 8434.746957591282, 8451.577421129043, 8454.830903381899, 8531.01893282268, 8562.400631955828, 8343.749687509384, 8366.832663346699, 8370.581470926429, 8504.957475212708, 8514.403991720645, 4396.230659634552, 4502.122086193226, 4505.5719706627815, 4583.518730533313, 4598.195315819785, 1428.6856891483858, 1608.6621809420153, 1798.4804595683297, 1852.1572684428552, 1968.3209292793963, 788.3181048393213, 875.4878396138203, 900.2258058070055, 932.4079209701888, 943.7782121201512, 19751.901240493706, 20391.85999023821, 20741.40732408449, 20766.792332076788, 21042.684359734616, 904.3850181876387, 906.7053207707521, 936.5738588942015, 955.9856602150974, 964.2719746591418, 9989.700308990741, 10240.948795255976, 10794.24440289188, 10895.83656245157, 10941.28881622708, 469204.0, 587946.0, 649329.0, 664453.6777316083, 667382.0, 92844.0, 144338.27830860778, 145396.27301863424, 145637.8154327686, 147272.52727472803, 941.3692825806863, 945.4894357047255, 1005.8491226316111, 1009.9646512372032, 1014.1633152649094, 15373.923130384277, 17074.0, 18949.715754263972, 19081.233221182574, 19438.222471101, 50670.0, 51522.0, 51556.226656600935, 52333.0, 53608.195877061095, 73841.63079184784, 78105.60947195228, 78366.2163378359, 78367.0, 78472.60763696145, 7800.726974555863, 7906.723264685708, 8064.879026814485, 8188.344932405513, 8228.876566851382, 11734.061275097802, 12401.465582308998, 12529.0, 12717.300548469922, 12907.483700651868, 8974.730758077269, 10777.62278320255, 10778.191635627203, 11022.834657479983, 11064.059554937598, 9486.383385079947, 10428.322159059637, 10743.488114373522, 10761.461926903467, 10896.673099807018, 11019.779604407706, 11275.887241127484, 11371.31772093677, 11464.770704586028, 11545.0, 7688.538687678757, 7842.9215707842195, 7948.125706172432, 7967.362610991223, 8369.581520924052, 8076.192380761877, 10065.245106616887, 10312.329698569869, 10468.528916198637, 10742.83885741699], "value": 9164.593395320215, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1447.4427345471993, 1464.963375915602, 1499.3028241867532, 1798.5953160538872, 1842.1894366478748, 7972.282494575487, 8416.83166336673, 8418.410711250215, 8420.452670576407, 8422.11567785383, 7365.558066516, 7601.77194684159, 7614.8857767133395, 7985.201479852012, 8194.46735962162, 2265.026038803318, 2303.0557471436714, 2307.6077066898583, 2308.0, 2309.58427483053, 2258.5369999150134, 2286.7941885230325, 2305.8270629702756, 2310.549442858643, 2315.6758053872386, 2290.0954123121355, 2291.942701432461, 2318.686977258074, 2321.3732292281074, 2326.6277395616703, 2254.4589298568308, 2281.212981521389, 2282.8173746100274, 2286.668433077211, 2287.782660647244, 2204.647256438962, 2304.0783686525356, 2305.3545007397956, 2306.5963456395066, 2308.9769102309097, 8180.631871565734, 8258.917410825936, 8262.0, 8265.0, 8269.793255168608, 4974.701517908937, 5144.511271429208, 5146.665466744687, 5155.097857874857, 5172.741362931838, 3212.405704944569, 3254.1864533866533, 3396.6433524480067, 3474.9305013899834, 3484.7734897231853, 1015.096564057989, 1045.4929359260752, 1058.560697310615, 1069.8127827630212, 1076.9192310576739, 10401.375917444977, 10718.035376816124, 10725.678229653124, 10742.624008159677, 10751.978562036673, 4249.192653395845, 4320.092780516064, 4416.447944006999, 4451.310046942729, 4453.621442177387, 8469.32245420365, 8471.152884711479, 8471.534065626327, 8475.279601233964, 8499.70251041223, 5017.874553136127, 5023.748812559357, 5029.547340739277, 5030.220315851049, 5035.521625445577, 4110.671146308288, 4269.252880745857, 4649.163150632919, 4726.810927562928, 4731.503192164842, 3037.4684430224625, 3085.7222849943614, 3303.141183292313, 3692.778433294011, 3697.1126929537254, 983.2527279267665, 1140.0993215359772, 1142.6514912951463, 1144.6165534546021, 1158.988410115888, 878.7599830816337, 880.264978742757, 886.4902680958346, 896.4710820615737, 910.6311943662827, 5344.518993290622, 5374.758135883894, 5553.861153471114, 5606.85982850432, 5640.266765320564, 963.0562049191691, 963.3545524498603, 992.8560358747941, 996.5664935752826, 1024.9795004099876, 3603.4774957631, 3612.223371975038, 3784.545854497423, 3784.9810750946067, 3789.8673546425744, 9680.0, 10113.898861011443, 10115.291929564859, 10117.19062475015, 10187.337823041478, 9314.347995640375, 9741.659041933499, 9742.805143897082, 10056.59773609062, 10059.748506287255, 7471.103467583925, 7808.5314881107315, 7810.804729881685, 7812.57030863308, 7816.960915195387, 4557.726536407826, 5327.174287985368, 5402.35171779389, 5491.862703432286, 5603.859903502445, 8936.910630893868, 8998.910010899806, 9118.0, 9267.351285409957, 9303.0, 9100.044495327895, 9343.953280233827, 9375.812483750424, 9494.05059494045, 9553.904460955302, 6852.931470685428, 7100.7869763907165, 7528.51064680794, 7764.728234511765, 7769.145394006542, 7322.560646361236, 7792.1428642848105, 7813.257740514642, 7917.485363451357, 7931.2068793120225, 6928.064711263914, 6969.7909062728195, 7073.469489788184, 7084.2915708428745, 7095.716171353088, 6848.178218613798, 6872.965635171792, 6873.175218973755, 6940.09778728762, 6990.720371185299, 6510.837229069311, 6554.442872355712, 6637.369449902251, 6653.068570400244, 6663.800085997428, 4196.9370459442525, 4673.0, 4745.008209443017, 4774.140654682191, 4792.137415265146, 4724.220503616864, 4774.737389443614, 4891.046245981999, 4907.067657145131, 4924.4090709115135], "value": 4334.169117243395, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.420864045353, 303.4371241347301, 311.4938225383752, 315.4542641230671, 318.2727467736223, 5365.275687782148, 5444.7822087116565, 5466.152746324321, 5476.808311709091, 5488.6981216033155, 4204.831806727727, 4665.963941190871, 4838.798346752228, 4859.0, 5052.368453943257, 3421.43546314859, 3468.393031219539, 3839.790466003214, 3873.360895452247, 3993.682084911972, 4049.0, 4054.776987265699, 4055.5538890721996, 4065.247929133105, 4098.938515922264, 3647.270545890819, 3897.142628621699, 3942.5268967723914, 3970.305196590585, 3996.3006473867104, 3362.663733626642, 3372.409828280041, 3917.6669983051474, 4055.047063939972, 4084.5893678778257, 3037.0, 3508.5438892944007, 3513.1744040150543, 3527.94708079379, 3537.3809583322814, 4878.365812444395, 5163.948360516384, 5216.973915130438, 5218.217267409881, 5228.686278823283, 3863.4784304118866, 4370.497392799805, 4456.398386217851, 4636.953630463686, 4659.953400465985, 5551.974174075139, 5564.4713752193475, 5575.581831362623, 5579.69311687857, 5613.915791263134, 3853.9614603853884, 3858.5144719283, 3867.284552357823, 3868.303705333039, 3893.0851249956236, 4318.658280922445, 5518.570864942338, 5573.634459557435, 5581.441855814427, 5831.737571809236, 3850.403187505941, 3890.5179864021543, 3909.8240579173435, 3909.980450097731, 3911.7848518331766, 3966.781826999543, 3983.322835117979, 4023.6177563131455, 4050.3519436890547, 4069.927204960236, 4076.3274059779806, 4249.553796851348, 4539.228331183706, 4649.837255696035, 4722.574968252874, 4574.725516469022, 5547.699119024174, 5555.805546805843, 5567.886422715473, 5581.860453488614, 1266.461753754669, 1280.295837289487, 1283.7496688145723, 1283.8010108433207, 1305.3016636099642, 692.4564217089561, 831.243568352796, 840.7393707950555, 855.2601999270586, 872.2019352292708, 4491.124230775033, 4658.790354434059, 4814.6870453420415, 5065.468125846806, 5077.847664570068, 5146.202338637517, 5288.65623734456, 5515.310586176728, 5527.39198688144, 5529.709822655514, 3959.6634286086005, 4109.8972525686495, 4673.322368256584, 4908.797344650512, 4986.055438379035, 2316.6756654068445, 2329.7087864017, 2331.836771426018, 2633.867437002069, 2725.9727402726116, 5113.284140220372, 5136.815174591777, 5238.397584277857, 5604.271444712162, 5659.0, 4516.0, 4737.957649317144, 5124.5131712487255, 5313.0, 5592.748326325325, 4614.423197100363, 5142.97428512855, 5147.408048074444, 5585.497305242467, 5636.026601246112, 4456.148065238315, 5398.163284690879, 5403.459654034565, 5442.884208737279, 5449.6730196188355, 4972.65191436596, 5065.873353166053, 5093.719845408538, 5314.893702125859, 5456.890862182813, 4932.4081110267, 5141.254518094781, 5599.0, 5610.803621873215, 5646.576506762091, 3542.1321776165005, 3622.6558476944647, 3628.219932714479, 3659.341318562684, 3701.8704345347783, 4818.63913158592, 4900.0, 5286.0705842368025, 5302.840914772563, 5302.840914772563, 4429.604674527513, 4465.96314506126, 5131.692098474104, 5225.346831646044, 5243.217306115786, 4593.058423023339, 4657.090592856913, 4837.903241935212, 4960.355153829979, 5216.869578260574, 4815.975920120377, 5099.974500127476, 5114.800635726525, 5130.48695130484, 5255.13290307095, 4818.855434336975, 5084.7966081356335, 5251.715503755879, 5526.640768350045, 5576.944230557642, 4485.0, 4781.784819683053, 4902.803887844446, 5559.4982257984875, 5594.608377413542], "value": 3917.765723944741, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.7090621938783, 697.9441644668427, 764.5947647746697, 779.4699604269094, 789.885466607342, 13853.861461385382, 14039.859601403983, 14077.873770098393, 14085.366158522866, 14109.082909610881, 12861.035422343315, 12967.157134786232, 12975.416106275217, 12977.74044519109, 12984.870151298484, 619.4301242856583, 646.0954663471155, 794.3565711773482, 818.4148333941231, 820.0159808230147, 780.3562061299423, 826.7726375246795, 835.8913341265627, 837.8952630921135, 843.0978852627691, 741.7292688168807, 882.2589025218815, 883.7039591736743, 889.5952341684518, 890.4034297021011, 657.7270432770395, 682.8258794007546, 882.9426087304337, 882.9514376709278, 884.0143240287098, 507.6877720202086, 508.8269988203991, 510.8620672418463, 613.9109829074804, 634.2547506679633, 13693.589192324247, 13717.382717777624, 13742.0, 13743.244121573309, 13747.450101995908, 12672.746545069047, 12707.618771436872, 12777.808332875014, 12858.614241572768, 12982.935085324607, 11630.592929247436, 11857.525698972031, 11886.320394313016, 11936.28382297065, 11983.400829958555, 4062.1875624874847, 4100.36444351126, 4125.814338354781, 4346.15250026245, 4388.188185185751, 84421.0, 89055.55472222598, 91436.0, 93681.59477607842, 96417.55373669471, 10130.645427410152, 10138.239632027628, 10373.740656483647, 10433.2696711231, 10522.210834187466, 19618.11718472672, 19702.113404896812, 19734.506637334183, 19753.0, 19771.308004219783, 6344.904826427607, 6988.0, 7170.533915295489, 7183.138023437221, 7556.848863022708, 4068.267711811843, 4092.2633925893624, 4099.508059032935, 4115.279826030433, 4115.506139263308, 2676.9196924092307, 3070.2784845561273, 3180.189051791778, 3195.6165260168927, 3208.53476245943, 598.6408155106988, 608.7534548507773, 614.1770028162284, 616.5221952986471, 616.5600304816242, 560.2772423573545, 585.1164741240757, 592.4963780786297, 597.5548216578677, 604.7853012180612, 6672.933270667231, 8127.796805079802, 8135.877961830577, 8151.5109093454585, 8511.829763404821, 574.873527823878, 591.1635036423498, 596.8090211132396, 603.0411645483701, 606.6178307666142, 4057.797110144481, 4172.895677608084, 4208.894777630584, 4233.0, 4293.162833247487, 72025.6398718003, 72360.63819680868, 97557.56106097404, 102755.0, 108502.0, 100198.49900750449, 101249.49375253076, 103641.48179259055, 104432.95567044384, 104534.0, 87582.0, 88119.0, 88123.0, 90549.54725226332, 91797.0, 8058.274755272054, 8205.671773129008, 8249.711260105867, 8309.293710034715, 8384.118928207465, 39613.811585652475, 48735.0, 56381.71809141092, 57588.0, 58665.70667146637, 57436.0, 69134.65432673006, 71496.5700685973, 73167.63416182886, 77357.22642773726, 11900.702482438008, 12069.57756478519, 12146.331951742739, 12209.067570598654, 12643.431045603154, 12289.324087175291, 12339.69150771202, 13091.214527128403, 13649.454021839412, 13995.300234988006, 10905.0, 12666.683332916382, 13322.800157997832, 13358.265295408846, 13603.251821149932, 8740.956295218482, 12459.252444853339, 12714.554990575285, 12719.300438475602, 13440.46238150463, 12475.0, 12619.873801262238, 12649.683757905757, 12897.935510322388, 12946.870531294944, 10774.784504310026, 10803.243772936134, 11091.33451992883, 11178.664640060812, 11492.770144596894, 11209.607663731731, 11215.60745373908, 11407.942960285145, 11415.429228538374, 11441.828372574251], "value": 7033.413286530426, "unit": "score"}], "name": "dromaeo_css", "value": 6968.258818136282, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 162.0, 169.0, 114.0, 113.0, 113.0, 112.0, 113.0, 113.0, 114.0], "value": 123.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 113.0, 114.0, 146.0, 114.0, 114.0, 122.0, 142.0, 179.0, 114.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 183.0, 185.0, 182.0, 184.0, 184.0, 182.0, 183.0, 184.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 89.0, 62.0, 59.0, 59.0, 59.0, 83.0, 59.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 69.0, 95.0, 70.0, 68.0, 70.0, 89.0, 67.0, 69.0, 92.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 84.0, 86.0, 83.0, 86.0, 84.0, 87.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 91.0, 93.0, 92.0, 94.0, 93.0, 92.0, 92.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 129.0, 131.0, 82.0, 84.0, 80.0, 83.0, 83.0, 83.0, 83.0], "value": 93.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 54.0, 55.0, 57.0, 58.0, 56.0, 58.0, 58.0, 57.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 67.0, 75.0, 72.0, 70.0, 69.0, 71.0, 72.0, 72.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 86.0, 86.0, 86.0, 82.0, 83.0, 79.0, 83.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 149.0, 148.0, 149.0, 152.0, 166.0, 153.0, 197.0, 176.0], "value": 158.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 168.0, 168.0, 168.0, 168.0, 159.0, 204.0, 224.0, 187.0], "value": 178.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 66.0, 67.0, 66.0, 67.0, 65.0, 80.0, 81.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1471.3999999999999, "alertThreshold": 2.0}]} 10:55:04 INFO - Return code: 0 10:55:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:55:04 INFO - # TBPL SUCCESS # 10:55:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:55:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:55:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:55:04 INFO - 2016-11-29 10:55:04.805 screenresolution[1651:9763] starting screenresolution argv=screenresolution get 10:55:04 INFO - 2016-11-29 10:55:04.809 screenresolution[1651:9763] Display 0: 1600x1200x32@60 10:55:04 INFO - 2016-11-29 10:55:04.815 screenresolution[1652:9766] starting screenresolution argv=screenresolution list 10:55:04 INFO - Available Modes on Display 0 10:55:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:55:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:55:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:55:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:55:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:55:05 INFO - Intel Iris: 10:55:05 INFO - Chipset Model: Intel Iris 10:55:05 INFO - Type: GPU 10:55:05 INFO - Bus: Built-In 10:55:05 INFO - VRAM (Dynamic, Max): 1536 MB 10:55:05 INFO - Vendor: Intel (0x8086) 10:55:05 INFO - Device ID: 0x0a2e 10:55:05 INFO - Revision ID: 0x0009 10:55:05 INFO - Displays: 10:55:05 INFO - Display: 10:55:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:55:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:55:05 INFO - Display Serial Number: 1600x1200 60 10:55:05 INFO - Main Display: Yes 10:55:05 INFO - Mirror: Off 10:55:05 INFO - Online: Yes 10:55:05 INFO - Rotation: Supported 10:55:05 INFO - Return code: 0 10:55:05 INFO - Running post-action listener: _resource_record_post_action 10:55:05 INFO - [mozharness: 2016-11-29 18:55:05.064558Z] Finished run-tests step (success) 10:55:05 INFO - Running post-run listener: _resource_record_post_run 10:55:05 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36930560; Write bytes: 657084928; Read time: 371; Write time: 1894 10:55:05 INFO - TinderboxPrint: CPU usage
53.6% 10:55:05 INFO - TinderboxPrint: I/O read bytes / time
36,930,560 / 371 10:55:05 INFO - TinderboxPrint: I/O write bytes / time
657,084,928 / 1,894 10:55:05 INFO - TinderboxPrint: CPU idle
2,249.9 (46.4%) 10:55:05 INFO - TinderboxPrint: CPU system
971.5 (20.0%) 10:55:05 INFO - TinderboxPrint: CPU user
1,628.3 (33.6%) 10:55:05 INFO - TinderboxPrint: Swap in / out
385,019,904 / 0 10:55:05 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342383104; Write bytes: 333920256; Read time: 26713; Write time: 617 10:55:05 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 35066880; Write bytes: 305617408; Read time: 350; Write time: 1209 10:55:05 INFO - Running post-run listener: _upload_blobber_files 10:55:05 INFO - Blob upload gear active. 10:55:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:55:05 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:55:05 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:55:05 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:55:05 INFO - (blobuploader) - INFO - Open directory for files ... 10:55:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:55:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:55:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:55:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:55:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:55:07 INFO - (blobuploader) - INFO - Done attempting. 10:55:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:55:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:55:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:55:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:55:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:55:09 INFO - (blobuploader) - INFO - Done attempting. 10:55:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:55:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:55:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:55:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:55:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:55:12 INFO - (blobuploader) - INFO - Done attempting. 10:55:12 INFO - (blobuploader) - INFO - Iteration through files over. 10:55:12 INFO - Return code: 0 10:55:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:55:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:55:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01784422a09ea34b36a5e22b8c5b2a3033522990bb82bdc187f792afc2eff3fa8207f78394e4b868bcb30a9839744c8dd9d6346ae509b5a24d47bd62764a6e28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0bc15ba4de5b883c677cf5bb7c8866b26164d7edb5bcbdbe9254dc3b787017fd730f5ce80fdab17797d882f62be37026faf33b970356fd8bee5fa24289bac0e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/991a98843b3e3a4a13572269e6fbda296ce2cb5c3318eba94d46ae4b31ea22fb1982da49e943d774477fcdadfcfd140040a845b3497225dabd7a3d71dbdf8185"} 10:55:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:55:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:55:12 INFO - Contents: 10:55:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01784422a09ea34b36a5e22b8c5b2a3033522990bb82bdc187f792afc2eff3fa8207f78394e4b868bcb30a9839744c8dd9d6346ae509b5a24d47bd62764a6e28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0bc15ba4de5b883c677cf5bb7c8866b26164d7edb5bcbdbe9254dc3b787017fd730f5ce80fdab17797d882f62be37026faf33b970356fd8bee5fa24289bac0e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/991a98843b3e3a4a13572269e6fbda296ce2cb5c3318eba94d46ae4b31ea22fb1982da49e943d774477fcdadfcfd140040a845b3497225dabd7a3d71dbdf8185"} 10:55:12 INFO - Running post-run listener: copy_logs_to_upload_dir 10:55:12 INFO - Copying logs to upload dir... 10:55:12 INFO - mkdir: /builds/slave/test/build/upload/logs 10:55:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.125738 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-29 10:55:12.387756) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 10:55:12.391359) ========= 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.gAOp6HJMyp/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.irHuhbWSn3/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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/01784422a09ea34b36a5e22b8c5b2a3033522990bb82bdc187f792afc2eff3fa8207f78394e4b868bcb30a9839744c8dd9d6346ae509b5a24d47bd62764a6e28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0bc15ba4de5b883c677cf5bb7c8866b26164d7edb5bcbdbe9254dc3b787017fd730f5ce80fdab17797d882f62be37026faf33b970356fd8bee5fa24289bac0e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/991a98843b3e3a4a13572269e6fbda296ce2cb5c3318eba94d46ae4b31ea22fb1982da49e943d774477fcdadfcfd140040a845b3497225dabd7a3d71dbdf8185"} build_url:https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011164 build_url: 'https://queue.taskcluster.net/v1/task/QPjpNCjLR-KRpBlXopjtZA/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/01784422a09ea34b36a5e22b8c5b2a3033522990bb82bdc187f792afc2eff3fa8207f78394e4b868bcb30a9839744c8dd9d6346ae509b5a24d47bd62764a6e28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0bc15ba4de5b883c677cf5bb7c8866b26164d7edb5bcbdbe9254dc3b787017fd730f5ce80fdab17797d882f62be37026faf33b970356fd8bee5fa24289bac0e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/991a98843b3e3a4a13572269e6fbda296ce2cb5c3318eba94d46ae4b31ea22fb1982da49e943d774477fcdadfcfd140040a845b3497225dabd7a3d71dbdf8185"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 10:55:12.425977) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 10:55:12.426310) ========= 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.gAOp6HJMyp/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.irHuhbWSn3/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.005021 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 10:55:12.476516) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 10:55:12.476840) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 10:55:12.477187) ========= ========= Total master_lag: 0.19 =========