builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0137 starttime: 1479148906.62 results: success (0) buildid: 20161114074144 builduid: c0ce765cf2c74afa85cbf7cb62a44e31 revision: 8cdff71808a0e11361bca7f218658c4343c730ca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 10:41:46.624301) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 10:41:46.624745) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 10:41:46.625025) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 10:41:46.649556) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:41:46.649843) ========= 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.ItGMS7IYsL/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.G7st70XK7x/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-14 10:41:46-- 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% 9.51M=0.001s 2016-11-14 10:41:46 (9.51 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104593 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:41:46.776819) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:41:46.777147) ========= 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.ItGMS7IYsL/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.G7st70XK7x/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.029441 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:41:46.830802) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 10:41:46.833123) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8cdff71808a0e11361bca7f218658c4343c730ca --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8cdff71808a0e11361bca7f218658c4343c730ca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ItGMS7IYsL/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.G7st70XK7x/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-14 10:41:46,914 truncating revision to first 12 chars 2016-11-14 10:41:46,914 Setting DEBUG logging. 2016-11-14 10:41:46,914 attempt 1/10 2016-11-14 10:41:46,914 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8cdff71808a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 10:41:47,986 unpacking tar archive at: mozilla-aurora-8cdff71808a0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.327796 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 10:41:48.183048) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 10:41:48.186406) ========= script_repo_revision: 8cdff71808a0e11361bca7f218658c4343c730ca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 10:41:48.186818) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 10:41:48.187085) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 10:41:48.205467) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-14 10:41:48.205796) ========= /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.ItGMS7IYsL/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.G7st70XK7x/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:41:48 INFO - MultiFileLogger online at 20161114 10:41:48 in /builds/slave/test 10:41:48 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:41:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:41:48 INFO - {'append_to_log': False, 10:41:48 INFO - 'base_work_dir': '/builds/slave/test', 10:41:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:41:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:41:48 INFO - 'branch': 'Mozilla-Aurora', 10:41:48 INFO - 'buildbot_json_path': 'buildprops.json', 10:41:48 INFO - 'config_files': ('talos/mac_config.py',), 10:41:48 INFO - 'default_actions': ('clobber', 10:41:48 INFO - 'read-buildbot-config', 10:41:48 INFO - 'download-and-extract', 10:41:48 INFO - 'populate-webroot', 10:41:48 INFO - 'create-virtualenv', 10:41:48 INFO - 'install', 10:41:48 INFO - 'run-tests'), 10:41:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:41:48 INFO - 'download_minidump_stackwalk': True, 10:41:48 INFO - 'download_symbols': 'ondemand', 10:41:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:41:48 INFO - 'tooltool.py': '/tools/tooltool.py', 10:41:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:41:48 INFO - '/tools/misc-python/virtualenv.py')}, 10:41:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:41:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:41:48 INFO - 'installer_path': 'installer.exe', 10:41:48 INFO - 'log_level': 'info', 10:41:48 INFO - 'log_name': 'talos', 10:41:48 INFO - 'log_to_console': True, 10:41:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:41:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:41:48 INFO - 'opt_config_files': (), 10:41:48 INFO - 'pip_index': False, 10:41:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:41:48 INFO - 'cmd': ('bash', 10:41:48 INFO - '-c', 10:41:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:41:48 INFO - 'enabled': True, 10:41:48 INFO - 'halt_on_failure': False, 10:41:48 INFO - 'name': 'check_screen_resolution'},), 10:41:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:41:48 INFO - 'cmd': ('bash', 10:41:48 INFO - '-c', 10:41:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:41:48 INFO - 'enabled': True, 10:41:48 INFO - 'halt_on_failure': False, 10:41:48 INFO - 'name': 'check_screen_resolution'},), 10:41:48 INFO - 'run_cmd_checks_enabled': True, 10:41:48 INFO - 'sps_profile': False, 10:41:48 INFO - 'sps_profile_interval': 0, 10:41:48 INFO - 'suite': 'dromaeojs', 10:41:48 INFO - 'system_bits': '32', 10:41:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:41:48 INFO - 'title': 't-yosemite-r7-0137', 10:41:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:41:48 INFO - 'use_talos_json': True, 10:41:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:41:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:41:48 INFO - 'work_dir': 'build'} 10:41:48 INFO - [mozharness: 2016-11-14 18:41:48.412605Z] Running clobber step. 10:41:48 INFO - Running pre-action listener: _resource_record_pre_action 10:41:48 INFO - Running main action method: clobber 10:41:48 INFO - rmtree: /builds/slave/test/build 10:41:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:41:50 INFO - Running post-action listener: _resource_record_post_action 10:41:50 INFO - [mozharness: 2016-11-14 18:41:50.803957Z] Finished clobber step (success) 10:41:50 INFO - [mozharness: 2016-11-14 18:41:50.804171Z] Running read-buildbot-config step. 10:41:50 INFO - Running pre-action listener: _resource_record_pre_action 10:41:50 INFO - Running main action method: read_buildbot_config 10:41:50 INFO - Using buildbot properties: 10:41:50 INFO - { 10:41:50 INFO - "project": "", 10:41:50 INFO - "product": "firefox", 10:41:50 INFO - "script_repo_revision": "production", 10:41:50 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:41:50 INFO - "repository": "", 10:41:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:41:50 INFO - "buildid": "20161114074144", 10:41:50 INFO - "pgo_build": "False", 10:41:50 INFO - "basedir": "/builds/slave/test", 10:41:50 INFO - "buildnumber": 128, 10:41:50 INFO - "slavename": "t-yosemite-r7-0137", 10:41:50 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 10:41:50 INFO - "platform": "yosemite_r7", 10:41:50 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:41:50 INFO - "branch": "mozilla-aurora", 10:41:50 INFO - "repo_path": "releases/mozilla-aurora", 10:41:50 INFO - "revision": "8cdff71808a0e11361bca7f218658c4343c730ca", 10:41:50 INFO - "stage_platform": "macosx64", 10:41:50 INFO - "builduid": "c0ce765cf2c74afa85cbf7cb62a44e31", 10:41:50 INFO - "slavebuilddir": "test" 10:41:50 INFO - } 10:41:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 10:41:50 INFO - Running post-action listener: _resource_record_post_action 10:41:50 INFO - [mozharness: 2016-11-14 18:41:50.809629Z] Finished read-buildbot-config step (success) 10:41:50 INFO - [mozharness: 2016-11-14 18:41:50.809760Z] Running download-and-extract step. 10:41:50 INFO - Running pre-action listener: _resource_record_pre_action 10:41:50 INFO - Running main action method: download_and_extract 10:41:50 INFO - mkdir: /builds/slave/test/build/tests 10:41: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:41:50 INFO - trying https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 10:41:50 INFO - Downloading https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:41:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 10:41:52 INFO - Downloaded 1199 bytes. 10:41:52 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:41:52 INFO - Using the following test package requirements: 10:41:52 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:41:52 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:41:52 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 10:41:52 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:41:52 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 10:41:52 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:41:52 INFO - u'jsshell-mac.zip'], 10:41:52 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:41:52 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 10:41:52 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:41:52 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:41:52 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 10:41:52 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:41:52 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 10:41:52 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:41:52 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 10:41:52 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:41:52 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 10:41:52 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:41:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:41:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:41:52 INFO - Fetch https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:41:52 INFO - Http code: 200 10:41:52 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 10:41:52 INFO - x-amz-cf-id: irzfGJVwulUbDaDYifsZ-zthd36fwqrfZU05XjW4MkCd9RBV2dzLUA== 10:41:52 INFO - x-amz-version-id: qEZpoxVrb8K_lOtz8d1xXpN4NyRiiL2Q 10:41:53 INFO - Expected file size: 16327509 10:41:53 INFO - Obtained file size: 16327509 10:41:59 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:41:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:41:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:41:59 INFO - Fetch https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:42:00 INFO - Http code: 200 10:42:00 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 10:42:00 INFO - x-amz-cf-id: HxcjKOkEqnLNovs6VxiEvfJuQtX8d0henRvAKXZvpTWMgVSuUnSS-Q== 10:42:00 INFO - x-amz-version-id: qEZpoxVrb8K_lOtz8d1xXpN4NyRiiL2Q 10:42:00 INFO - Expected file size: 16327509 10:42:00 INFO - Obtained file size: 16327509 10:42:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 10:42:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:42:07 INFO - Fetch https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 10:42:08 INFO - Http code: 200 10:42:08 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 10:42:08 INFO - x-amz-cf-id: hXjoBJeczjSnl2zRwCZw5ZI1ftfeoTt5VJxaGwIjdzdFZQCugOv4FQ== 10:42:08 INFO - x-amz-version-id: jXsupoNyJ.YzlDUqzWygU8BmOV_JFB8G 10:42:08 INFO - Expected file size: 14409038 10:42:08 INFO - Obtained file size: 14409038 10:42:08 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:42:08 INFO - trying https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:42:08 INFO - Downloading https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 10:42:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 10:42:11 INFO - Downloaded 114362341 bytes. 10:42:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:42:11 INFO - mkdir: /builds/slave/test/properties 10:42:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:42:11 INFO - Writing to file /builds/slave/test/properties/build_url 10:42:11 INFO - Contents: 10:42:11 INFO - build_url:https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:42:12 INFO - Running post-action listener: _resource_record_post_action 10:42:12 INFO - Running post-action listener: set_extra_try_arguments 10:42:12 INFO - [mozharness: 2016-11-14 18:42:12.295888Z] Finished download-and-extract step (success) 10:42:12 INFO - [mozharness: 2016-11-14 18:42:12.296021Z] Running populate-webroot step. 10:42:12 INFO - Running pre-action listener: _resource_record_pre_action 10:42:12 INFO - Running main action method: populate_webroot 10:42:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:42:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:42:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:42:12 INFO - '--tppagecycles', 10:42:12 INFO - '7'], 10:42:12 INFO - 'tests': ['tsvgm']}}, 10:42:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:42:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:42:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:42:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:42:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:42:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:42:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:42:12 INFO - 'talos_options': ['--disable-e10s'], 10:42:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:42:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:42:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:42:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:42:12 INFO - 'talos_options': ['--disable-e10s'], 10:42:12 INFO - 'tests': ['damp', 'tps']}, 10:42:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:42:12 INFO - 'tests': ['damp', 'tps']}, 10:42:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:42:12 INFO - 'tests': ['dromaeo_dom']}, 10:42:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:42:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:42:12 INFO - 'tests': ['basic_compositor_video']}, 10:42:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:42:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:42:12 INFO - 'tests': ['a11yr', 10:42:12 INFO - 'ts_paint', 10:42:12 INFO - 'tpaint', 10:42:12 INFO - 'sessionrestore', 10:42:12 INFO - 'sessionrestore_no_auto_restore', 10:42:12 INFO - 'tabpaint']}, 10:42:12 INFO - 'other-e10s': {'tests': ['a11yr', 10:42:12 INFO - 'ts_paint', 10:42:12 INFO - 'tpaint', 10:42:12 INFO - 'sessionrestore', 10:42:12 INFO - 'sessionrestore_no_auto_restore', 10:42:12 INFO - 'tabpaint']}, 10:42:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:42:12 INFO - 'ts_paint', 10:42:12 INFO - 'tpaint', 10:42:12 INFO - 'sessionrestore', 10:42:12 INFO - 'sessionrestore_no_auto_restore', 10:42:12 INFO - 'tabpaint']}, 10:42:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:42:12 INFO - 'ts_paint', 10:42:12 INFO - 'tpaint', 10:42:12 INFO - 'sessionrestore', 10:42:12 INFO - 'sessionrestore_no_auto_restore', 10:42:12 INFO - 'tabpaint']}, 10:42:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:42:12 INFO - 'tests': ['a11yr', 10:42:12 INFO - 'ts_paint', 10:42:12 INFO - 'tpaint', 10:42:12 INFO - 'sessionrestore', 10:42:12 INFO - 'sessionrestore_no_auto_restore', 10:42:12 INFO - 'tabpaint']}, 10:42:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:42:12 INFO - 'tests': ['a11yr', 10:42:12 INFO - 'ts_paint', 10:42:12 INFO - 'tpaint', 10:42:12 INFO - 'sessionrestore', 10:42:12 INFO - 'sessionrestore_no_auto_restore', 10:42:12 INFO - 'tabpaint']}, 10:42:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:42:12 INFO - 'tests': ['tsvgx', 10:42:12 INFO - 'tsvgr_opacity', 10:42:12 INFO - 'tart', 10:42:12 INFO - 'tscrollx', 10:42:12 INFO - 'cart']}, 10:42:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:42:12 INFO - 'tsvgr_opacity', 10:42:12 INFO - 'tart', 10:42:12 INFO - 'tscrollx', 10:42:12 INFO - 'cart']}, 10:42:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:42:12 INFO - 'talos_options': ['--disable-e10s'], 10:42:12 INFO - 'tests': ['tp5o']}, 10:42:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:42:12 INFO - 'tests': ['tp5o']}, 10:42:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:42:12 INFO - 'talos_options': ['--disable-e10s', 10:42:12 INFO - '--xperf_path', 10:42:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:42:12 INFO - 'tests': ['tp5n']}, 10:42:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:42:12 INFO - 'talos_options': ['--xperf_path', 10:42:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:42:12 INFO - 'tests': ['tp5n']}}, 10:42:12 INFO - 'talos.zip': {'path': '', 10:42:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:42:12 INFO - Running post-action listener: _resource_record_post_action 10:42:12 INFO - [mozharness: 2016-11-14 18:42:12.314265Z] Finished populate-webroot step (success) 10:42:12 INFO - [mozharness: 2016-11-14 18:42:12.314397Z] Running create-virtualenv step. 10:42:12 INFO - Running pre-action listener: _resource_record_pre_action 10:42:12 INFO - Running main action method: create_virtualenv 10:42:12 INFO - Creating virtualenv /builds/slave/test/build/venv 10:42:12 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:42:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:42:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:42:12 INFO - Using real prefix '/tools/python27' 10:42:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:42:13 INFO - Installing distribute.............................................................................................................................................................................................done. 10:42:15 INFO - Installing pip.................done. 10:42:15 INFO - Return code: 0 10:42:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:42:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:42:16 INFO - Reading from file tmpfile_stdout 10:42:16 INFO - Output received: 10:42:16 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:42:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:42:16 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:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:16 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:42:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:16 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 0x10d79c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d712570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b255d1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b0d30>, '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 0x10d416db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a8b20>, '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.ItGMS7IYsL/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.G7st70XK7x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:42:16 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:42:16 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:42:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ItGMS7IYsL/Render', 10:42:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:16 INFO - 'HOME': '/Users/cltbld', 10:42:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:16 INFO - 'LOGNAME': 'cltbld', 10:42:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:16 INFO - 'MOZ_NO_REMOTE': '1', 10:42:16 INFO - 'NO_EM_RESTART': '1', 10:42:16 INFO - 'PAGER': '/bin/cat', 10:42:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:16 INFO - 'PWD': '/builds/slave/test', 10:42:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:16 INFO - 'SHELL': '/bin/bash', 10:42:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G7st70XK7x/Listeners', 10:42:16 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 10:42:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:16 INFO - 'USER': 'cltbld', 10:42:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:16 INFO - 'XPC_FLAGS': '0x0', 10:42:16 INFO - 'XPC_SERVICE_NAME': '0', 10:42:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:16 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:42:16 INFO - Cleaning up... 10:42:16 INFO - Return code: 0 10:42:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:42:16 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:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:16 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:42:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:16 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 0x10d79c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d712570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b255d1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b0d30>, '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 0x10d416db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a8b20>, '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.ItGMS7IYsL/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.G7st70XK7x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:42:16 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:42:16 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:42:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ItGMS7IYsL/Render', 10:42:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:16 INFO - 'HOME': '/Users/cltbld', 10:42:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:16 INFO - 'LOGNAME': 'cltbld', 10:42:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:16 INFO - 'MOZ_NO_REMOTE': '1', 10:42:16 INFO - 'NO_EM_RESTART': '1', 10:42:16 INFO - 'PAGER': '/bin/cat', 10:42:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:16 INFO - 'PWD': '/builds/slave/test', 10:42:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:16 INFO - 'SHELL': '/bin/bash', 10:42:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G7st70XK7x/Listeners', 10:42:16 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 10:42:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:16 INFO - 'USER': 'cltbld', 10:42:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:16 INFO - 'XPC_FLAGS': '0x0', 10:42:16 INFO - 'XPC_SERVICE_NAME': '0', 10:42:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:16 INFO - Downloading/unpacking psutil>=3.1.1 10:42:16 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:42:16 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:42:16 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:42:16 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:42:16 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:42:16 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:42:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:42:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:42:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:42:20 INFO - Installing collected packages: psutil 10:42:20 INFO - Running setup.py install for psutil 10:42:20 INFO - building 'psutil._psutil_osx' extension 10:42:20 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:42:20 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:42:20 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:42:20 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:42:21 INFO - building 'psutil._psutil_posix' extension 10:42:21 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:42:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:42:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:42:21 INFO - ^ 10:42:21 INFO - 1 warning generated. 10:42:21 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:42:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:42:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:42:21 INFO - Successfully installed psutil 10:42:21 INFO - Cleaning up... 10:42:21 INFO - Return code: 0 10:42:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:42:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d79c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d712570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b255d1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b0d30>, '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 0x10d416db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a8b20>, '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.ItGMS7IYsL/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.G7st70XK7x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:42:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:42:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:42:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ItGMS7IYsL/Render', 10:42:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:21 INFO - 'HOME': '/Users/cltbld', 10:42:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:21 INFO - 'LOGNAME': 'cltbld', 10:42:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:21 INFO - 'MOZ_NO_REMOTE': '1', 10:42:21 INFO - 'NO_EM_RESTART': '1', 10:42:21 INFO - 'PAGER': '/bin/cat', 10:42:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:21 INFO - 'PWD': '/builds/slave/test', 10:42:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:21 INFO - 'SHELL': '/bin/bash', 10:42:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G7st70XK7x/Listeners', 10:42:21 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 10:42:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:21 INFO - 'USER': 'cltbld', 10:42:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:21 INFO - 'XPC_FLAGS': '0x0', 10:42:21 INFO - 'XPC_SERVICE_NAME': '0', 10:42:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:42:21 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:42:21 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:42:21 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:42:21 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:42:21 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:42:21 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:42:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:42:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:42:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:42:24 INFO - Installing collected packages: mozsystemmonitor 10:42:24 INFO - Running setup.py install for mozsystemmonitor 10:42:24 INFO - Successfully installed mozsystemmonitor 10:42:24 INFO - Cleaning up... 10:42:24 INFO - Return code: 0 10:42:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:42: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:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42: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:42:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:24 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 0x10d79c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d712570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b255d1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b0d30>, '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 0x10d416db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a8b20>, '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.ItGMS7IYsL/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.G7st70XK7x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:42:24 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:42:24 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:42:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ItGMS7IYsL/Render', 10:42:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:24 INFO - 'HOME': '/Users/cltbld', 10:42:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:24 INFO - 'LOGNAME': 'cltbld', 10:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:24 INFO - 'MOZ_NO_REMOTE': '1', 10:42:24 INFO - 'NO_EM_RESTART': '1', 10:42:24 INFO - 'PAGER': '/bin/cat', 10:42:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:24 INFO - 'PWD': '/builds/slave/test', 10:42:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:24 INFO - 'SHELL': '/bin/bash', 10:42:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G7st70XK7x/Listeners', 10:42:24 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 10:42:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:24 INFO - 'USER': 'cltbld', 10:42:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:24 INFO - 'XPC_FLAGS': '0x0', 10:42:24 INFO - 'XPC_SERVICE_NAME': '0', 10:42:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:24 INFO - Downloading/unpacking jsonschema==2.5.1 10:42:24 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:42:24 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:42:24 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:42:24 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:42:24 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:42:24 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:42:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:42:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:42:27 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:42:27 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:42:27 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:42:27 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:42:27 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:42:27 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:42:27 INFO - Downloading functools32-3.2.3-2.tar.gz 10:42:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:42:27 INFO - warning: no files found matching '*.txt' 10:42:27 INFO - no previously-included directories found matching 'build' 10:42:27 INFO - no previously-included directories found matching 'dist' 10:42:27 INFO - no previously-included directories found matching '.git*' 10:42:27 INFO - Installing collected packages: jsonschema, functools32 10:42:27 INFO - Running setup.py install for functools32 10:42:27 INFO - warning: no files found matching '*.txt' 10:42:27 INFO - no previously-included directories found matching 'build' 10:42:27 INFO - no previously-included directories found matching 'dist' 10:42:27 INFO - no previously-included directories found matching '.git*' 10:42:27 INFO - Successfully installed jsonschema functools32 10:42:27 INFO - Cleaning up... 10:42:27 INFO - Return code: 0 10:42:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:42:27 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:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:27 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:42:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:27 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 0x10d79c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d712570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b255d1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b0d30>, '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 0x10d416db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a8b20>, '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.ItGMS7IYsL/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.G7st70XK7x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:42:27 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:42:27 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:42:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ItGMS7IYsL/Render', 10:42:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:27 INFO - 'HOME': '/Users/cltbld', 10:42:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:27 INFO - 'LOGNAME': 'cltbld', 10:42:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:27 INFO - 'MOZ_NO_REMOTE': '1', 10:42:27 INFO - 'NO_EM_RESTART': '1', 10:42:27 INFO - 'PAGER': '/bin/cat', 10:42:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:27 INFO - 'PWD': '/builds/slave/test', 10:42:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:27 INFO - 'SHELL': '/bin/bash', 10:42:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G7st70XK7x/Listeners', 10:42:27 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 10:42:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:27 INFO - 'USER': 'cltbld', 10:42:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:27 INFO - 'XPC_FLAGS': '0x0', 10:42:27 INFO - 'XPC_SERVICE_NAME': '0', 10:42:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:42:28 INFO - Cleaning up... 10:42:28 INFO - Return code: 0 10:42:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:42: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:42:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42: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:42:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42: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', '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 0x10d79c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d712570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b255d1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b0d30>, '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 0x10d416db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a8b20>, '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.ItGMS7IYsL/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.G7st70XK7x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:42: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:42: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 blobuploader==1.2.4 10:42:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ItGMS7IYsL/Render', 10:42:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:28 INFO - 'HOME': '/Users/cltbld', 10:42:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:28 INFO - 'LOGNAME': 'cltbld', 10:42:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:28 INFO - 'MOZ_NO_REMOTE': '1', 10:42:28 INFO - 'NO_EM_RESTART': '1', 10:42:28 INFO - 'PAGER': '/bin/cat', 10:42:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:28 INFO - 'PWD': '/builds/slave/test', 10:42:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:28 INFO - 'SHELL': '/bin/bash', 10:42:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G7st70XK7x/Listeners', 10:42:28 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 10:42:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:28 INFO - 'USER': 'cltbld', 10:42:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:28 INFO - 'XPC_FLAGS': '0x0', 10:42:28 INFO - 'XPC_SERVICE_NAME': '0', 10:42:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:28 INFO - Downloading/unpacking blobuploader==1.2.4 10:42: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:42: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:42: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:42: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:42: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:42: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:42:31 INFO - Downloading blobuploader-1.2.4.tar.gz 10:42:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:42:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:42: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:42: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:42: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:42: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:42: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:42: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:42:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:42:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:42: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:42: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:42: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:42: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:42: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:42: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:42:32 INFO - Downloading docopt-0.6.1.tar.gz 10:42:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:42:32 INFO - Installing collected packages: blobuploader, requests, docopt 10:42:32 INFO - Running setup.py install for blobuploader 10:42:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:42:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:42:32 INFO - Running setup.py install for requests 10:42:33 INFO - Running setup.py install for docopt 10:42:33 INFO - Successfully installed blobuploader requests docopt 10:42:33 INFO - Cleaning up... 10:42:33 INFO - Return code: 0 10:42:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:42:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:33 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 0x10d79c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d712570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b255d1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b0d30>, '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 0x10d416db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a8b20>, '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.ItGMS7IYsL/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.G7st70XK7x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:42:33 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:42:33 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:42:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ItGMS7IYsL/Render', 10:42:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:33 INFO - 'HOME': '/Users/cltbld', 10:42:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:33 INFO - 'LOGNAME': 'cltbld', 10:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:33 INFO - 'MOZ_NO_REMOTE': '1', 10:42:33 INFO - 'NO_EM_RESTART': '1', 10:42:33 INFO - 'PAGER': '/bin/cat', 10:42:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:33 INFO - 'PWD': '/builds/slave/test', 10:42:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:33 INFO - 'SHELL': '/bin/bash', 10:42:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G7st70XK7x/Listeners', 10:42:33 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 10:42:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:33 INFO - 'USER': 'cltbld', 10:42:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:33 INFO - 'XPC_FLAGS': '0x0', 10:42:33 INFO - 'XPC_SERVICE_NAME': '0', 10:42:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:42:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-mzY7sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:42:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-6PgRIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:42:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-HdAoy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:42:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-kJDIBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:42:33 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-paRsKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:42:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-X1PUPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:42:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-U85abN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:42:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-dUut0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:42:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-tmTBTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:42:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-CeEc9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:42:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-DkednN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:42:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-FdE4n0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:42:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-KeVqIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:42:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-_n3cnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:42:35 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Ld8Dhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:42:35 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-X95uJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:42:35 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Awoq4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:42:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:42:35 INFO - Running setup.py install for manifestparser 10:42:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:42:35 INFO - Running setup.py install for mozcrash 10:42:35 INFO - Running setup.py install for mozdebug 10:42:35 INFO - Running setup.py install for mozdevice 10:42:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:42:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:42:35 INFO - Running setup.py install for mozfile 10:42:36 INFO - Running setup.py install for mozhttpd 10:42:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:42:36 INFO - Running setup.py install for mozinfo 10:42:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:42:36 INFO - Running setup.py install for mozInstall 10:42:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:42:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:42:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:42:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:42:36 INFO - Running setup.py install for mozleak 10:42:36 INFO - Running setup.py install for mozlog 10:42:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:42:36 INFO - Running setup.py install for moznetwork 10:42:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:42:36 INFO - Running setup.py install for mozprocess 10:42:37 INFO - Running setup.py install for mozprofile 10:42:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:42:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:42:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:42:37 INFO - Running setup.py install for mozrunner 10:42:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:42:37 INFO - Running setup.py install for mozscreenshot 10:42:37 INFO - Running setup.py install for moztest 10:42:37 INFO - Running setup.py install for mozversion 10:42:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:42:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:42:37 INFO - Cleaning up... 10:42:37 INFO - Return code: 0 10:42:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:42:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:42:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:37 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 0x10d79c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d712570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b255d1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b0d30>, '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 0x10d416db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a8b20>, '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.ItGMS7IYsL/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.G7st70XK7x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:42:37 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:42:37 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:42:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ItGMS7IYsL/Render', 10:42:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:37 INFO - 'HOME': '/Users/cltbld', 10:42:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:37 INFO - 'LOGNAME': 'cltbld', 10:42:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:37 INFO - 'MOZ_NO_REMOTE': '1', 10:42:37 INFO - 'NO_EM_RESTART': '1', 10:42:37 INFO - 'PAGER': '/bin/cat', 10:42:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:37 INFO - 'PWD': '/builds/slave/test', 10:42:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:37 INFO - 'SHELL': '/bin/bash', 10:42:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G7st70XK7x/Listeners', 10:42:37 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 10:42:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:37 INFO - 'USER': 'cltbld', 10:42:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:37 INFO - 'XPC_FLAGS': '0x0', 10:42:37 INFO - 'XPC_SERVICE_NAME': '0', 10:42:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:42:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-vK37n7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:42:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-73Xafy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:42:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Umu15J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:42:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-LC8DOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:42:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-ckUNyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:42:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-StCcsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:42:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-alOCdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:42:38 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-aJyk5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:42:39 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:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:42:39 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-dQzVWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:42:39 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:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:42:39 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-THeALj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:42:39 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:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:42:39 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Ky6li1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:42:39 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:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:42:39 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-B0oOQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:42:39 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:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:42:39 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Y0jCp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:42:39 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:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:42:39 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-KT7w6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:42:39 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:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:42:39 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-qmOh3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:42:39 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:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:42:39 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-f3f9N1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:42:39 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:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:42:39 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-6icKuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:42:40 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:42:40 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:42:40 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:42:40 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:42:40 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:42:40 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:42:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:42:40 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:42:40 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:42:40 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:42:40 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:42:40 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:42:40 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:42:42 INFO - Downloading blessings-1.6.tar.gz 10:42:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:42:42 INFO - Installing collected packages: blessings 10:42:42 INFO - Running setup.py install for blessings 10:42:42 INFO - Successfully installed blessings 10:42:42 INFO - Cleaning up... 10:42:42 INFO - Return code: 0 10:42:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:42:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:42:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:42:43 INFO - Reading from file tmpfile_stdout 10:42:43 INFO - Current package versions: 10:42:43 INFO - blessings == 1.6 10:42:43 INFO - blobuploader == 1.2.4 10:42:43 INFO - docopt == 0.6.1 10:42:43 INFO - functools32 == 3.2.3-2 10:42:43 INFO - jsonschema == 2.5.1 10:42:43 INFO - manifestparser == 1.1 10:42:43 INFO - mozInstall == 1.12 10:42:43 INFO - mozcrash == 1.0 10:42:43 INFO - mozdebug == 0.1 10:42:43 INFO - mozdevice == 0.48 10:42:43 INFO - mozfile == 1.2 10:42:43 INFO - mozhttpd == 0.7 10:42:43 INFO - mozinfo == 0.9 10:42:43 INFO - mozleak == 0.1 10:42:43 INFO - mozlog == 3.4 10:42:43 INFO - moznetwork == 0.27 10:42:43 INFO - mozprocess == 0.23 10:42:43 INFO - mozprofile == 0.28 10:42:43 INFO - mozrunner == 6.13 10:42:43 INFO - mozscreenshot == 0.1 10:42:43 INFO - mozsystemmonitor == 0.3 10:42:43 INFO - moztest == 0.7 10:42:43 INFO - mozversion == 1.4 10:42:43 INFO - psutil == 3.1.1 10:42:43 INFO - requests == 1.2.3 10:42:43 INFO - wsgiref == 0.1.2 10:42:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:44 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 0x10d79c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d712570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b255d1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b0d30>, '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 0x10d416db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a8b20>, '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.ItGMS7IYsL/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.G7st70XK7x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:42:44 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:42:44 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:42:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ItGMS7IYsL/Render', 10:42:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:44 INFO - 'HOME': '/Users/cltbld', 10:42:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:44 INFO - 'LOGNAME': 'cltbld', 10:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:44 INFO - 'MOZ_NO_REMOTE': '1', 10:42:44 INFO - 'NO_EM_RESTART': '1', 10:42:44 INFO - 'PAGER': '/bin/cat', 10:42:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:44 INFO - 'PWD': '/builds/slave/test', 10:42:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:44 INFO - 'SHELL': '/bin/bash', 10:42:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G7st70XK7x/Listeners', 10:42:44 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 10:42:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:44 INFO - 'USER': 'cltbld', 10:42:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:44 INFO - 'XPC_FLAGS': '0x0', 10:42:44 INFO - 'XPC_SERVICE_NAME': '0', 10:42:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:44 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:42:44 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:42:44 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:42:44 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:42:44 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:42:44 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:42:44 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:42:44 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:42:44 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:42:44 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:42:44 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:42:44 INFO - Cleaning up... 10:42:44 INFO - Return code: 0 10:42:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:42:44 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:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:44 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:42:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:44 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 0x10d79c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d712570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b255d1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b0d30>, '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 0x10d416db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a8b20>, '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.ItGMS7IYsL/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.G7st70XK7x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:42:44 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:42:44 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:42:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ItGMS7IYsL/Render', 10:42:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:44 INFO - 'HOME': '/Users/cltbld', 10:42:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:44 INFO - 'LOGNAME': 'cltbld', 10:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:44 INFO - 'MOZ_NO_REMOTE': '1', 10:42:44 INFO - 'NO_EM_RESTART': '1', 10:42:44 INFO - 'PAGER': '/bin/cat', 10:42:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:44 INFO - 'PWD': '/builds/slave/test', 10:42:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:44 INFO - 'SHELL': '/bin/bash', 10:42:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G7st70XK7x/Listeners', 10:42:44 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 10:42:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:44 INFO - 'USER': 'cltbld', 10:42:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:44 INFO - 'XPC_FLAGS': '0x0', 10:42:44 INFO - 'XPC_SERVICE_NAME': '0', 10:42:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:42:44 INFO - Cleaning up... 10:42:45 INFO - Return code: 0 10:42:45 INFO - Running post-action listener: _resource_record_post_action 10:42:45 INFO - Running post-action listener: _start_resource_monitoring 10:42:45 INFO - Starting resource monitoring. 10:42:45 INFO - [mozharness: 2016-11-14 18:42:45.042853Z] Finished create-virtualenv step (success) 10:42:45 INFO - [mozharness: 2016-11-14 18:42:45.043753Z] Running install step. 10:42:45 INFO - Running pre-action listener: _resource_record_pre_action 10:42:45 INFO - Running main action method: install 10:42:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:42:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:42:45 INFO - Reading from file tmpfile_stdout 10:42:45 INFO - Detecting whether we're running mozinstall >=1.0... 10:42:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:42:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:42:45 INFO - Reading from file tmpfile_stdout 10:42:45 INFO - Output received: 10:42:45 INFO - Usage: mozinstall [options] installer 10:42:45 INFO - Options: 10:42:45 INFO - -h, --help show this help message and exit 10:42:45 INFO - -d DEST, --destination=DEST 10:42:45 INFO - Directory to install application into. [default: 10:42:45 INFO - "/builds/slave/test"] 10:42:45 INFO - --app=APP Application being installed. [default: firefox] 10:42:45 INFO - mkdir: /builds/slave/test/build/application 10:42:45 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:42:45 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:43:16 INFO - Reading from file tmpfile_stdout 10:43:16 INFO - Output received: 10:43:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:43:16 INFO - Running post-action listener: _resource_record_post_action 10:43:16 INFO - [mozharness: 2016-11-14 18:43:16.965225Z] Finished install step (success) 10:43:16 INFO - [mozharness: 2016-11-14 18:43:16.965375Z] Running run-tests step. 10:43:16 INFO - Running pre-action listener: _resource_record_pre_action 10:43:16 INFO - Running main action method: run_tests 10:43:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:43:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:43:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:43:16 INFO - 2016-11-14 10:43:16.979 screenresolution[1640:7486] starting screenresolution argv=screenresolution get 10:43:16 INFO - 2016-11-14 10:43:16.982 screenresolution[1640:7486] Display 0: 1600x1200x32@60 10:43:16 INFO - 2016-11-14 10:43:16.988 screenresolution[1641:7488] starting screenresolution argv=screenresolution list 10:43:16 INFO - Available Modes on Display 0 10:43:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:43:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:43:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:43:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:43:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:43:17 INFO - Intel Iris: 10:43:17 INFO - Chipset Model: Intel Iris 10:43:17 INFO - Type: GPU 10:43:17 INFO - Bus: Built-In 10:43:17 INFO - VRAM (Dynamic, Max): 1536 MB 10:43:17 INFO - Vendor: Intel (0x8086) 10:43:17 INFO - Device ID: 0x0a2e 10:43:17 INFO - Revision ID: 0x0009 10:43:17 INFO - Displays: 10:43:17 INFO - Display: 10:43:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:43:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:43:17 INFO - Display Serial Number: 1600x1200 60 10:43:17 INFO - Main Display: Yes 10:43:17 INFO - Mirror: Off 10:43:17 INFO - Online: Yes 10:43:17 INFO - Rotation: Supported 10:43:17 INFO - Return code: 0 10:43:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:43:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:43:17 INFO - Python 2.7.3 10:43:17 INFO - Return code: 0 10:43:17 INFO - grabbing minidump binary from tooltool 10:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:17 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 0x10d5b0d30>, '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 0x10d416db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:43:17 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:43:17 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:43:17 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:43:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:43:17 INFO - Return code: 0 10:43:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:43:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:43:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:43:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:43:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:43:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--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:43:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0137 --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:43:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ItGMS7IYsL/Render', 10:43:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:43:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:43:17 INFO - 'HOME': '/Users/cltbld', 10:43:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:43:17 INFO - 'LOGNAME': 'cltbld', 10:43:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:43:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:43:17 INFO - 'MOZ_NO_REMOTE': '1', 10:43:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:43:17 INFO - 'NO_EM_RESTART': '1', 10:43:17 INFO - 'PAGER': '/bin/cat', 10:43:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:43:17 INFO - 'PWD': '/builds/slave/test', 10:43:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:43:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:43:17 INFO - 'SHELL': '/bin/bash', 10:43:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G7st70XK7x/Listeners', 10:43:17 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 10:43:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:43:17 INFO - 'USER': 'cltbld', 10:43:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:43:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:43:17 INFO - 'XPC_FLAGS': '0x0', 10:43:17 INFO - 'XPC_SERVICE_NAME': '0', 10:43:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:43:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--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:43:17 INFO - mozversion application_buildid: 20161114074144 10:43:17 INFO - mozversion application_changeset: 8cdff71808a0e11361bca7f218658c4343c730ca 10:43:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:43:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:43:17 INFO - mozversion application_name: Firefox 10:43:17 INFO - mozversion application_remotingname: firefox-dev 10:43:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:43:17 INFO - mozversion application_vendor: Mozilla 10:43:17 INFO - mozversion application_version: 52.0a2 10:43:17 INFO - mozversion platform_buildid: 20161114074144 10:43:17 INFO - mozversion platform_changeset: 8cdff71808a0e11361bca7f218658c4343c730ca 10:43:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:43:17 INFO - mozversion platform_version: 52.0a2 10:43:17 INFO - using testdate: 1479148997 10:43:17 INFO - actual date: 1479148997 10:43:17 INFO - starting webserver on 'localhost:49275' 10:43:17 INFO - SUITE-START | Running 2 tests 10:43:17 INFO - TEST-START | dromaeo_css 10:43:17 INFO - operating with platform_type : mac_ 10:43:17 INFO - Initialising browser for dromaeo_css test... 10:43:17 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmp_QsbzW/profile http://localhost:49275/getInfo.html) 10:43:19 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 10:43:20 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 10:43:20 INFO - PROCESS | 1651 | colorDepth:24 10:43:20 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 10:43:20 INFO - PROCESS | 1651 | __metrics 10:43:20 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:43:20 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:43:20 INFO - TEST-INFO | 1651: exit 0 10:43:20 INFO - Browser initialized. 10:43:20 INFO - Running cycle 1/1 for dromaeo_css test... 10:43:20 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmp_QsbzW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:43:32 INFO - PROCESS | 1653 | RSS: Main: 216879104 10:43:32 INFO - PROCESS | 1653 | 10:44:23 INFO - PROCESS | 1653 | 1479149063745 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:46:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 10:46:40 INFO - PROCESS | 1653 | RSS: Main: 309231616 10:46:40 INFO - PROCESS | 1653 | 10:49:42 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 10:49:43 INFO - PROCESS | 1653 | RSS: Main: 355639296 10:49:43 INFO - PROCESS | 1653 | 10:52:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 10:52:46 INFO - PROCESS | 1653 | RSS: Main: 328261632 10:52:46 INFO - PROCESS | 1653 | 10:55:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 10:55:49 INFO - PROCESS | 1653 | RSS: Main: 329891840 10:55:49 INFO - PROCESS | 1653 | 10:58:52 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 10:58:53 INFO - PROCESS | 1653 | RSS: Main: 311779328 10:58:53 INFO - PROCESS | 1653 | 11:01:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 11:01:56 INFO - PROCESS | 1653 | RSS: Main: 435109888 11:01:56 INFO - PROCESS | 1653 | 11:01:56 INFO - PROCESS | 1653 | __start_tp_report 11:01:56 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 11:01:56 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 11:01:56 INFO - PROCESS | 1653 | |i|pagename|runs| 11:01:56 INFO - PROCESS | 1653 | |0;dojo.html;3247.9999999999995;3266.28141808802;3317.8672853085877;3327.584051993501;3333.100062982993;5657;5740.339860915986;5761.395053519378;5762.337331206907;5783.392743761897;5727.398623144572;5783.884322313551;5789.189513468118;5793.420657934204;5794.71026448678;5203.713795741232;5207.817726379578;5211.478852114787;5254.947450525503;5262;5128.307678463416;5134.204198349262;5303.071962406582;5315.867103322428;5367.919481207766;5188.870278243036;5227.3465816772905;5234.6859188448625;5247.212918062283;5248.134057880445;5226.895462090756;5251.949610078;5269.894602107936;5272.630915835893;5278.47215278473;5081.364829396325;5194.220866869962;5205.115130427836;5213.635045546814;5242.18746094356;5738.196652468658;5750.683712395817;5753.712314384264;5757.453041961007;5759.280089988752;6167.383261673796;6181.505479561669;6188.535859810488;6196.977498712707;6198.783042593533;6043.274807023141;6263.812085637438;6289.842753931142;6301.369863013708;6303.401176888188;4793.7363445010515;5732.398098199712;5755.165501002373;5760.654360738369;5807;6262.5929314594405;6298.7480500780375;6300.243970723543;6312.905306420407;6320.304766475684;5989.251343582052;6041.305249896231;6095.756169753249;6114.174586430775;6135.845176233341;6186.10301506279;6257.843553911189;6264.467520260829;6303.653299068504;6324.367563243731;6219.129321894939;6257.468115210167;6266.091416744546;6284.874302513924;6294.622322660655;6112.1995365628145;6154.415330543591;6176.444120029129;6183.752649893954;6263;4914.533119353656;4935.481774413634;4967.478414766398;4978.5270399312;4999.800007999712;4455.6212721918355;4458.175237581057;4460.130274596422;4484.573965473275;4485.6860019798305;5733.226014487991;5756.5682573807135;5818.330891947396;5843.211166492469;5855.092460668603;6199.535034872402;6254.124422580842;6297.748090076346;6312.621242725451;6332.398422149889;5794.449527294901;5800.854978625568;5830.737616807254;5841.591088623755;5873;5312.203169524602;5450.127979523258;5458.536024437967;5468.72656367188;5470.56235501159;6221.813345599639;6233.407826256498;6246.500279977682;6247.656378899204;6302.65335406557;6314.147590075281;6315.778947736807;6316.14732011191;6317.147185130132;6317.7157027932935;6295.842603934938;6314.968425158028;6331.810045698637;6338.651374174465;6344.397282258178;6033.155358249936;6047.516198704181;6112.847178820387;6145.8156255312415;6246;5352.956173546121;5915.881682366243;6233.75064997395;6316.021016742412;6336.619802811846;6205.47253483459;6208.751649933952;6210.378962103753;6232.096346029891;6236.376362363727;6002.369751176064;6121.602095863755;6208.658523781055;6212.223472065992;6238.625682459067;5865.912011319919;5904;5921.289445266596;5935.643861368331;6096.786612468542;5982.910256346071;6154.323024467394;6156.479679934813;6165.0444181152;6190;5850.327212370632;5879.412058794086;5933.703314834327;5958.910616340671;6034.275886893425 11:01:56 INFO - PROCESS | 1653 | |1;ext.html;457.387101284279;524.8609118583579;532.0715351711265;554.9139883318087;582.9300483941927;35178.120546986276;38862.611373886255;39222.41166382506;39955.801325960194;41268;40825;41241.793791031;41260.58739412605;41281.79359103208;41326.380104298456;9225.446473211612;9363.812723745521;9392.295577831655;9407.717768466957;9413.81172376552;9248.491332976684;9343.71968840936;9345.95327023364;9354.017828128051;9368.156865882069;9358.57886395114;9406.43561386319;9422.62309507619;9424.104710052534;9424.34029617927;9312;9356.298277629137;9365.250779937656;9366.625334986593;9383.859242111408;9192.080791920822;9277.768055798591;9282.675106371313;9287.349885508018;9296.674616388394;38756.03109922246;39231.80384098061;39307.803460982796;39334.60665393338;39371.212575748614;3271.1495011296997;3373.443381841993;3397.932041359159;3677.9448308275396;3803.9809800951093;27440.557073670807;28344.433111337865;29635.259118521994;30469.847650761825;30847.69152308493;2862.912093404493;3157.2422618571377;3160.225744692542;3219.146926064595;3292.670732926712;499805.9999999964;499973;500598;500899;502445;27464.03875864335;57323.713381432826;57870.71064644734;57907.42092579105;57955;32874.67125328764;32961.83519082389;34170;34207;34211.31577368489;14262.57212283633;14364.640883977776;14381.352839122264;14424.278786060655;14458.421663133357;11996.820047699292;12088.697782555331;12093.516259349528;12099.879001209874;12114;7662.080550333884;7679.385649148055;7729.8454030919065;7738.187490313549;7746.264104910025;3153.936921261562;3197.3125777957853;3197.344544368399;3201.231704390976;3233.919152021171;824.2952275804221;872.882160908282;903.336048004716;939.145377706284;1011.6509804117715;28747.85626071898;29124.563131553045;29208;29929.70070299269;30168;849.2781136034445;1028.8919663435381;1029.4235228272041;1065.6589891234885;1066.504075604832;13122.475100996044;13241.3379331035;14795.668389844966;15063.548093556992;15184.544463665887;706907;709034;709668;713103;713763;55078.44921550733;55590.72204638951;55666.443335566124;55725;55770.72114639482;1353.9932300338633;1354.4040622126233;1385.7228554289181;1385.8267716535433;1387.5213051497217;23595.646065309378;27546.86226568854;28284.434311313245;28568.022294084847;28763;47867.76066119647;48489.51510484991;48499.75750121227;48504.02991940212;48511.27233091436;615793;615855;616992;617520.9123954531;620661;25622.69395754391;25797.871010644827;25891.61162582525;26021;43969.12061758811;47180;47541;48273;49019.26471102865;49019.50980490149;39253;39634.810955671375;40022.59977400189;40265.59734402619;41618.167636647704;37693;38377.232455350175;39091;39783.60216397799;40271;39005.609943901334;39028.609713903636;39174;39747.80126099351;39962.400563992145;33132.1716957078;34146.829265853514;35207.82396088003;35249.64750352463;35304.64695353116;22173.780442075196;25437.87281063583;25887.741122588533;26045.277485449024;26238.60642090332 11:01:56 INFO - PROCESS | 1653 | |2;jquery.html;356.29098094791374;369.09939747017273;372.67018688460706;388.8930544100373;408.77517365448995;14445.783313250296;14561.854381456182;15287.70684397341;19429.222831086776;19989.900050499728;15829.366825327003;18551.07244637766;18858.528536786587;19348.51628709283;19608;5661.179129026289;5969.552283578727;5970;5979.342272350038;5983.58114931955;5845.5908086433965;6137.723802428879;6489.383508566702;6557.442617377528;6645.136132302794;5953.613015154023;6567.868642627144;6575.506836987245;6578.703958321887;6606.14120164378;5931.584789064767;6040.0939859021055;6061.484773794233;6062.514998800108;6062.818115456544;5637.689927054011;5835.328937172195;5870.589058765888;5870.853228669274;5907.172995780594;13093.279869607262;13304.733905321937;13369.064165508418;13398.933005334911;24874.25377238686;2239.0595949701164;2263.2644390572964;2324.8721320327377;2339.0760649543336;2454.8895299711558;9527.333086683935;14437.71124577513;15024.624384390369;15359.924805263636;15360.078395296317;1606.36548563318;1742.5295170303948;1785.3309302592577;1820.708686610136;1860.9534761630932;86166;109529;110364;122926;123865;9289.953550232342;11097;11612.12909031826;11715.824262636068;11790.764184716429;14687.485937992333;14742.631434214016;14788.260586970824;14797.482088127079;14848.331825067899;8193.67225310981;8490.533020683923;8495.872561911576;8562;8568.400211985227;7578.810529736801;7661.310482056641;8264.669413223524;8278.130796266425;8448.619812108469;4368.497622773358;4443.289073748249;4523.072770082125;4584.77076146198;4621.32990716351;1697.73685078813;1873.8101305670998;1904.3620387170176;1905.9047047647562;1923.1634239106033;814;899.5142622983647;962.2157941277836;965.5413678502655;992.2910454459222;20204.48344443653;20777.480562985744;21753;22345.73988574619;22717.2048978285;861.3970220845433;938.9953050234706;956.2541217850088;958.324381311174;971.7182017214928;9892.653757118467;10689.30519516229;10763.977422144884;11178.385188814693;11379.601713939972;625090;633068;635625;640912;646412;124350;126178.36910815387;127883.36058319834;138298;142791.2860435712;977.1449981266392;984.1388784813288;1026.1790567545934;1026.2610920137488;1028.922830787694;16064.919675401548;18513.53716157107;18695.626087478446;18871.433856984313;19447.416577502696;35825.64174358223;49557.00885982332;50920.49079509157;52753.20870187027;54788;79467.10493550614;79470.20529794628;80078.5996070016;80895.12391446557;83337.58331208548;7946.205379462008;7958.602069896598;8046.637901294339;8173.610486217239;8178;10689.51897164645;11591.826122608336;11824.23142495735;12039.157258991787;12678.936605316914;10494.947525262325;10524.158067354747;10741.227697429837;10817.350958942488;11073.667789966314;10558.524866381185;10590.735231618963;10734.892651073702;10747.193960452842;10850.94574527154;10547.947260263649;10710.946445267724;10794.244402891722;10836.078933290759;11034.944825275823;7894.250046245597;7909.802254943443;8147.877781833158;8284.171582841667;8327.292180164754;10109.595616175271;10641.574337026432;10790.73023174427;10857.13142948578;10961.287516311415 11:01:56 INFO - PROCESS | 1653 | |3;mootools.html;1339.1764065099953;1402.8386735525416;1481.1261355800075;1643.6876993371266;1839.6872531669605;7619.542827430351;7993.360531157508;8004.319632831209;8312.293455056328;8326.625301861415;8204.753857384274;8341.165883411655;8367.037790654076;8372.330213582914;8375.329973602098;2298.2071185441037;2338.1816364272454;2341.0401735288533;2343.4024323797407;2343.6484527320868;2323.6398358254496;2358.7287461941924;2375.3824005758543;2380.654805053266;2388.379021454417;2277.191596983072;2285.200179937017;2288.1533832482005;2313.0516488239823;2336.100601268507;1962.2347284559035;2274.977250227493;2286.931392058241;2312.0636142362287;2312.479692069271;2304.0783686525356;2320.33870346952;2323.6514522821544;2325.5697695926137;2327.6392159215347;8289.378296627778;8341.45780524264;8361.623726932241;8372.455790373606;8372.623231954576;5014.598832093424;5035.949640503584;5219.6085293602755;5227.79088836446;5228.660137091115;3015.4873671475684;3470.854617976062;3499.842507087187;3557.839897204632;3583.835253542583;1044.2794471814423;1054.2000553309413;1081.6538707613568;1085.5766251162054;1099.7855418193456;10886.455677216187;11079.944600276947;11095.55617775288;11117.166212534012;11171.329720216812;4513.81944722214;4604.700694454849;4702.57676809082;4705.200115980291;4710.9764451177525;9559.139677429064;9562.995885432068;9569.473678947725;9582.233421326277;9589.760255993517;5218.869528261824;5240.161574148118;5242.580593552507;5245.344331958505;5260.9736951315;4206.936895946563;4339.067100573392;4569.885752856205;4689.3903792507435;4711.0342379812055;3758.1732018955786;3814.7520411173186;3843.9807800960816;3891.5330160380936;3903.902402439962;1061.9522121504551;1243.483954159032;1257.69186549296;1269.6318067760246;1276.4511260158222;799.8400319936036;887.8978917424579;896.4173287363266;903.9050899655441;910.4264313482464;5401.950454656632;5452.427495113036;5549.139883318092;5690.231818704505;5768.192453056576;976.9853452198223;1006.5520843224841;1011.9190464762802;1017.6692574913183;1041.4376236832177;3572.142685755452;3588.9102772430374;3708.128589781399;3771.0383852118084;3881.5536213335827;10011.299209055442;10223;10380.42907640072;10393.16854651626;10397.48012599367;10311.48442577868;10333.328333658288;10335.586576537005;10339.276250662382;10350.689479315633;7570.432217583703;7777.72777952769;8041.959790201128;8059.919400806034;8064.798380040428;4379.474463064453;5523.171524271392;5568.220449137043;5860.443257890493;5866.325372582208;9151.771205719644;9429.15137637616;9439.197668198005;9464.432134071978;9627.518624068629;9342.673006444742;9619.855702164334;9652.083052110038;9753.561089750836;9758.75603109928;7050.08720160316;7129.322714342129;7217.566945983258;7243.022192004223;7253;7746.147923728498;7798.805029874298;7812.1797211292;7812.296893279632;7817;6886.51794374389;6942.132233470817;7062.3996960258955;7167.139943206848;7194.856102877809;6970.830814729103;7037.120359955005;7068.363847253772;7201.099862517185;7238.27617238272;6628.502862285444;6799.11611490503;6801.795946121624;6808.829779255559;6812.693428795617;4722.409698787652;4790.712557246577;4836.419629644465;4868.537488938545;4922;4823.300621409946;4824.662273640812;4847.951520484891;5003.199488081889;5011.047900898818 11:01:56 INFO - PROCESS | 1653 | |4;prototype.html;264.60705851810064;305.5081319076288;309.2994367757031;314.3916521530838;315.3582459694522;5269.420363759983;5289.365276166861;5329.25390445337;5364;5405.810796622114;4472.44094488189;4564.748938808372;4625.011245164578;4973.577245934091;5212.947870521293;3987.7407968482103;3987.900302492432;3990.5211374635082;4028.6777057835448;4068.4507591475217;3980.3432433648413;3987.9800600996955;3998.600139985993;4024.879253622396;4042.3532234842432;3296.3077753671637;3494.9340451162384;3761.514201890265;4002.919941601166;4011.59884011598;3462.9134271643156;3959.1685745993377;3961.3859851723028;3967.127232008968;3987.7407968482103;3461.7671601816937;3470.444728843373;3474.2009337852437;3486.9302613947834;3532.2405682778067;4189.958100418988;5033.471485494044;5039.370078740158;5042.470540593222;5046.6719663221775;3794.810259487042;4300.763458009808;4537.841175558872;4540.477845047829;4607.516210797885;4555.316702494652;5399;5412.4858138477175;5444.156155795858;5479.835604931858;3293;3822.2164456286396;3823.8279277432302;3880.631340022693;3895.7662540247675;5168.121419358643;5500.4224556421395;5713.828665123681;5752.309722833287;5752.683602401867;3751.4185301278344;3879.18537107205;3879.670228030649;3880.8835734927998;3881.941770873439;3621.257642183346;3680.7485454945445;3970.622790834866;3983.1635356574866;3997.6202260785763;3917.5494818095713;4400.471943366816;4558.794854231567;4627.467841198305;4654.743989080565;4780.378550788375;5042.621803364762;5443.374011988592;5487.314085739283;5587;1260.848698156227;1273.7643535945879;1281.8205451236834;1286.2154086007529;1286.7040580666312;670.510527315053;839.085396917363;843.8144407108023;867.800405906645;888.8666699994923;4105.91788164241;4174.812133454002;4338.522762496123;4788.95211047892;4814.711117332971;4655.930161047587;5232.148479078911;5249.370075590878;5264.289106040552;5268.470125016248;3936.3308237599676;4280.587406155961;4563.201439748031;4825.831095911626;4863.540937718741;2762.392273699783;2783.359827239746;2806.5790131480035;2813.6084328320762;2822.957655635167;5287.41838397776;5441.183822426668;5532.446755324516;5545.417731138253;5555.972220138954;4587.747673877902;4923.606111511071;5338.412774594792;5416.916616676681;5473.223475801684;4851.870543499424;5185.585153187736;5224.582033437392;5474.069408200616;5476.890462190813;4399.392498151485;4931.0630980113665;5288.735563221747;5324.787008519771;5344.430511912259;4498.415206023196;4866.68599478146;4943.8516844494725;5262.842114736564;5306.787728490817;5112.795488180431;5281.563486671214;5354.303940487712;5462.289902312674;5551.084071128219;3317.3199494104133;3706.5366829146355;3729.3846515324667;3770.699108807457;3776.546814382292;4481.865544033684;4570.245909424908;5234.906037584844;5634.211210430625;5647.407022262603;5280.60395470349;5414.29614150158;5491.862703432446;5530.6128570999645;5607.495325420732;5205.609579281493;5602.030923610493;5623.847111342083;5650.542014223825;5665.801696940587;5219.243209734643;5368.436314186954;5369.785208591768;5388.595855310789;5398.678568215705;4147.917041659211;4297.935530966975;5335.359756829205;5386.245925570502;5464.93433780409;5459.317585301837;5468.507834294774;5477.67133971963;5482.77947431291;5487.368952570506 11:01:56 INFO - PROCESS | 1653 | |5;yui.html;679.8708245433368;704.788563430971;708.8475977664801;725.4232884856539;763.0233301374241;14072.859271407297;14649.487267945597;14650.560483185509;14692.632684182874;14703.70592588149;12932.094753367268;13048.347582620854;13061.60815175544;13064.93467532661;13074.542391016317;820.4748960665164;823.0288259853371;834.6744769539885;837.8659414493683;839.6389552492426;840.9663613455455;841.7222316635496;842.0148426341195;842.9199226073544;843.9998601657056;888.6800751729372;889.1641856654757;890.6526454682679;893.7229458867772;896.135229503529;875.4528419737663;879.4679219072465;879.7800549862567;880.5113162194979;882.668999125328;657.5265808617787;658.3219284137347;659.3176062775004;661.3683931845077;661.675778868351;12258.754824903452;12306.75386492274;12505.249685018836;12549.623511294585;12551.497940082385;10891.856355082682;10904.672859814218;11480.138989575815;11608.6517404478;11794.997425218866;10452;10928.071113955324;11075.280106793032;11109;11509.712257193638;4319.308910574325;4372.519022907478;4393.692441529094;4395;4534.501204867462;76868;86640.56679716625;88369.67445488322;89911;90770.18459630836;8665.913340866511;10121.24090693201;10297.02178293061;10314.1748660107;10326.277160598685;18992.62014759725;19031.809681903003;19120.90439547821;19230;19240.134193961025;6783.796486105425;6888.242293347727;7231.7107315707835;7528.360089392353;7541;4026.53694825093;4034.3948407738485;4151.875443736692;4273.059926816096;4327.783610819446;2835.0219174384806;3262.0866157475944;3328.1014126185796;3335.4163021471145;3344.347852168804;545.939946605873;550.3395924890148;579.7709904587748;608.8538750699888;636.3191385217865;574.4255744255744;584.1938125386939;590.8906852232265;604.2748701558148;606.9180660610849;7941.761747147595;8720.91279087201;8747.781305467286;8770.868436973451;8877.866831997526;533.815833537429;588.7556663984484;617.0836308082494;624.0919462182513;630.3035146163515;3945.92108157841;4032.7177097602885;4187.539370669224;4313.223619748475;4341.435613370241;97677;102718.48640756749;104148.47925760323;104377;106777;62887;64765.02852457217;73963.26036739671;93435;99733.50133249287;83612.31101511864;85096;87213;87233.1276687225;91686;9158.95420522893;9616.471094089893;9651;9654.613815447303;9668.226541876773;51610.48389516057;53021;53115.46884531105;53292.73353633207;54613.72693136509;44130;65144.348556515724;72592;72807;75306.24693752993;12109.69725756863;12269.93865030669;12368.814467782811;12430.689232769253;12902.741945161231;13366;13698.452061917811;13990.230537320545;14053.297335133408;14350.210738409487;12440.751184976069;12643.557475488315;12753.936230318788;13021.348932553525;13414.530491432753;12829.807552886901;13012.672497762718;13041.608751737464;13270.933645331712;13384.330783460982;12417.751644967231;12805.295708735735;13249.933750331187;13434.93282533581;13470.32648367558;10454.581816727246;10850;10853.131749460183;11504.137189710638;11538;10388.896111039096;10955.120144946202;11211.98184326829;11270.098392128773;11341.659750207507 11:01:56 INFO - PROCESS | 1653 | __end_tp_report 11:01:56 INFO - PROCESS | 1653 | __start_cc_report 11:01:56 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,2022 11:01:56 INFO - PROCESS | 1653 | __end_cc_report 11:01:56 INFO - PROCESS | 1653 | __startTimestamp1479150116647__endTimestamp 11:01:56 INFO - PROCESS | 1653 | 11:01:56 INFO - PROCESS | 1653 | ------- Summary: start ------- 11:01:56 INFO - PROCESS | 1653 | Number of tests: 6 11:01:56 INFO - PROCESS | 1653 | 11:01:56 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5757.39 Median:5933.70 stddev:645.91 (10.9%) stddev-sans-first:617.16 11:01:56 INFO - PROCESS | 1653 | Values: 3248.0 3266.3 3317.9 3327.6 3333.1 5657.0 5740.3 5761.4 5762.3 5783.4 5727.4 5783.9 5789.2 5793.4 5794.7 5203.7 5207.8 5211.5 5254.9 5262.0 5128.3 5134.2 5303.1 5315.9 5367.9 5188.9 5227.3 5234.7 5247.2 5248.1 5226.9 5251.9 5269.9 5272.6 5278.5 5081.4 5194.2 5205.1 5213.6 5242.2 5738.2 5750.7 5753.7 5757.5 5759.3 6167.4 6181.5 6188.5 6197.0 6198.8 6043.3 6263.8 6289.8 6301.4 6303.4 4793.7 5732.4 5755.2 5760.7 5807.0 6262.6 6298.7 6300.2 6312.9 6320.3 5989.3 6041.3 6095.8 6114.2 6135.8 6186.1 6257.8 6264.5 6303.7 6324.4 6219.1 6257.5 6266.1 6284.9 6294.6 6112.2 6154.4 6176.4 6183.8 6263.0 4914.5 4935.5 4967.5 4978.5 4999.8 4455.6 4458.2 4460.1 4484.6 4485.7 5733.2 5756.6 5818.3 5843.2 5855.1 6199.5 6254.1 6297.7 6312.6 6332.4 5794.4 5800.9 5830.7 5841.6 5873.0 5312.2 5450.1 5458.5 5468.7 5470.6 6221.8 6233.4 6246.5 6247.7 6302.7 6314.1 6315.8 6316.1 6317.1 6317.7 6295.8 6315.0 6331.8 6338.7 6344.4 6033.2 6047.5 6112.8 6145.8 6246.0 5353.0 5915.9 6233.8 6316.0 6336.6 6205.5 6208.8 6210.4 6232.1 6236.4 6002.4 6121.6 6208.7 6212.2 6238.6 5865.9 5904.0 5921.3 5935.6 6096.8 5982.9 6154.3 6156.5 6165.0 6190.0 5850.3 5879.4 5933.7 5958.9 6034.3 11:01:56 INFO - PROCESS | 1653 | 11:01:56 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:71894.62 Median:39691.31 stddev:165290.72 (416.4%) stddev-sans-first:165667.38 11:01:56 INFO - PROCESS | 1653 | Values: 457.4 524.9 532.1 554.9 582.9 35178.1 38862.6 39222.4 39955.8 41268.0 40825.0 41241.8 41260.6 41281.8 41326.4 9225.4 9363.8 9392.3 9407.7 9413.8 9248.5 9343.7 9346.0 9354.0 9368.2 9358.6 9406.4 9422.6 9424.1 9424.3 9312.0 9356.3 9365.3 9366.6 9383.9 9192.1 9277.8 9282.7 9287.3 9296.7 38756.0 39231.8 39307.8 39334.6 39371.2 3271.1 3373.4 3397.9 3677.9 3804.0 27440.6 28344.4 29635.3 30469.8 30847.7 2862.9 3157.2 3160.2 3219.1 3292.7 499806.0 499973.0 500598.0 500899.0 502445.0 27464.0 57323.7 57870.7 57907.4 57955.0 32874.7 32961.8 34170.0 34207.0 34211.3 14262.6 14364.6 14381.4 14424.3 14458.4 11996.8 12088.7 12093.5 12099.9 12114.0 7662.1 7679.4 7729.8 7738.2 7746.3 3153.9 3197.3 3197.3 3201.2 3233.9 824.3 872.9 903.3 939.1 1011.7 28747.9 29124.6 29208.0 29929.7 30168.0 849.3 1028.9 1029.4 1065.7 1066.5 13122.5 13241.3 14795.7 15063.5 15184.5 706907.0 709034.0 709668.0 713103.0 713763.0 55078.4 55590.7 55666.4 55725.0 55770.7 1354.0 1354.4 1385.7 1385.8 1387.5 23595.6 27546.9 28284.4 28568.0 28763.0 47867.8 48489.5 48499.8 48504.0 48511.3 615793.0 615855.0 616992.0 617520.9 620661.0 25622.7 25797.9 25891.6 26021.0 43969.1 47180.0 47541.0 48273.0 49019.3 49019.5 39253.0 39634.8 40022.6 40265.6 41618.2 37693.0 38377.2 39091.0 39783.6 40271.0 39005.6 39028.6 39174.0 39747.8 39962.4 33132.2 34146.8 35207.8 35249.6 35304.6 22173.8 25437.9 25887.7 26045.3 26238.6 11:01:56 INFO - PROCESS | 1653 | 11:01:56 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:35582.60 Median:2331.97 stddev:105899.12 (4541.2%) stddev-sans-first:106163.16 11:01:56 INFO - PROCESS | 1653 | Values: 356.3 369.1 372.7 388.9 408.8 14445.8 14561.9 15287.7 19429.2 19989.9 15829.4 18551.1 18858.5 19348.5 19608.0 5661.2 5969.6 5970.0 5979.3 5983.6 5845.6 6137.7 6489.4 6557.4 6645.1 5953.6 6567.9 6575.5 6578.7 6606.1 5931.6 6040.1 6061.5 6062.5 6062.8 5637.7 5835.3 5870.6 5870.9 5907.2 13093.3 13304.7 13369.1 13398.9 24874.3 2239.1 2263.3 2324.9 2339.1 2454.9 9527.3 14437.7 15024.6 15359.9 15360.1 1606.4 1742.5 1785.3 1820.7 1861.0 86166.0 109529.0 110364.0 122926.0 123865.0 9290.0 11097.0 11612.1 11715.8 11790.8 14687.5 14742.6 14788.3 14797.5 14848.3 8193.7 8490.5 8495.9 8562.0 8568.4 7578.8 7661.3 8264.7 8278.1 8448.6 4368.5 4443.3 4523.1 4584.8 4621.3 1697.7 1873.8 1904.4 1905.9 1923.2 814.0 899.5 962.2 965.5 992.3 20204.5 20777.5 21753.0 22345.7 22717.2 861.4 939.0 956.3 958.3 971.7 9892.7 10689.3 10764.0 11178.4 11379.6 625090.0 633068.0 635625.0 640912.0 646412.0 124350.0 126178.4 127883.4 138298.0 142791.3 977.1 984.1 1026.2 1026.3 1028.9 16064.9 18513.5 18695.6 18871.4 19447.4 35825.6 49557.0 50920.5 52753.2 54788.0 79467.1 79470.2 80078.6 80895.1 83337.6 7946.2 7958.6 8046.6 8173.6 8178.0 10689.5 11591.8 11824.2 12039.2 12678.9 10494.9 10524.2 10741.2 10817.4 11073.7 10558.5 10590.7 10734.9 10747.2 10850.9 10547.9 10710.9 10794.2 10836.1 11034.9 7894.3 7909.8 8147.9 8284.2 8327.3 10109.6 10641.6 10790.7 10857.1 10961.3 11:01:56 INFO - PROCESS | 1653 | 11:01:56 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5482.40 Median:4858.24 stddev:3031.69 (62.4%) stddev-sans-first:3024.20 11:01:56 INFO - PROCESS | 1653 | Values: 1339.2 1402.8 1481.1 1643.7 1839.7 7619.5 7993.4 8004.3 8312.3 8326.6 8204.8 8341.2 8367.0 8372.3 8375.3 2298.2 2338.2 2341.0 2343.4 2343.6 2323.6 2358.7 2375.4 2380.7 2388.4 2277.2 2285.2 2288.2 2313.1 2336.1 1962.2 2275.0 2286.9 2312.1 2312.5 2304.1 2320.3 2323.7 2325.6 2327.6 8289.4 8341.5 8361.6 8372.5 8372.6 5014.6 5035.9 5219.6 5227.8 5228.7 3015.5 3470.9 3499.8 3557.8 3583.8 1044.3 1054.2 1081.7 1085.6 1099.8 10886.5 11079.9 11095.6 11117.2 11171.3 4513.8 4604.7 4702.6 4705.2 4711.0 9559.1 9563.0 9569.5 9582.2 9589.8 5218.9 5240.2 5242.6 5245.3 5261.0 4206.9 4339.1 4569.9 4689.4 4711.0 3758.2 3814.8 3844.0 3891.5 3903.9 1062.0 1243.5 1257.7 1269.6 1276.5 799.8 887.9 896.4 903.9 910.4 5402.0 5452.4 5549.1 5690.2 5768.2 977.0 1006.6 1011.9 1017.7 1041.4 3572.1 3588.9 3708.1 3771.0 3881.6 10011.3 10223.0 10380.4 10393.2 10397.5 10311.5 10333.3 10335.6 10339.3 10350.7 7570.4 7777.7 8042.0 8059.9 8064.8 4379.5 5523.2 5568.2 5860.4 5866.3 9151.8 9429.2 9439.2 9464.4 9627.5 9342.7 9619.9 9652.1 9753.6 9758.8 7050.1 7129.3 7217.6 7243.0 7253.0 7746.1 7798.8 7812.2 7812.3 7817.0 6886.5 6942.1 7062.4 7167.1 7194.9 6970.8 7037.1 7068.4 7201.1 7238.3 6628.5 6799.1 6801.8 6808.8 6812.7 4722.4 4790.7 4836.4 4868.5 4922.0 4823.3 4824.7 4848.0 5003.2 5011.0 11:01:56 INFO - PROCESS | 1653 | 11:01:56 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4346.04 Median:4801.83 stddev:1310.87 (27.3%) stddev-sans-first:1278.25 11:01:56 INFO - PROCESS | 1653 | Values: 264.6 305.5 309.3 314.4 315.4 5269.4 5289.4 5329.3 5364.0 5405.8 4472.4 4564.7 4625.0 4973.6 5212.9 3987.7 3987.9 3990.5 4028.7 4068.5 3980.3 3988.0 3998.6 4024.9 4042.4 3296.3 3494.9 3761.5 4002.9 4011.6 3462.9 3959.2 3961.4 3967.1 3987.7 3461.8 3470.4 3474.2 3486.9 3532.2 4190.0 5033.5 5039.4 5042.5 5046.7 3794.8 4300.8 4537.8 4540.5 4607.5 4555.3 5399.0 5412.5 5444.2 5479.8 3293.0 3822.2 3823.8 3880.6 3895.8 5168.1 5500.4 5713.8 5752.3 5752.7 3751.4 3879.2 3879.7 3880.9 3881.9 3621.3 3680.7 3970.6 3983.2 3997.6 3917.5 4400.5 4558.8 4627.5 4654.7 4780.4 5042.6 5443.4 5487.3 5587.0 1260.8 1273.8 1281.8 1286.2 1286.7 670.5 839.1 843.8 867.8 888.9 4105.9 4174.8 4338.5 4789.0 4814.7 4655.9 5232.1 5249.4 5264.3 5268.5 3936.3 4280.6 4563.2 4825.8 4863.5 2762.4 2783.4 2806.6 2813.6 2823.0 5287.4 5441.2 5532.4 5545.4 5556.0 4587.7 4923.6 5338.4 5416.9 5473.2 4851.9 5185.6 5224.6 5474.1 5476.9 4399.4 4931.1 5288.7 5324.8 5344.4 4498.4 4866.7 4943.9 5262.8 5306.8 5112.8 5281.6 5354.3 5462.3 5551.1 3317.3 3706.5 3729.4 3770.7 3776.5 4481.9 4570.2 5234.9 5634.2 5647.4 5280.6 5414.3 5491.9 5530.6 5607.5 5205.6 5602.0 5623.8 5650.5 5665.8 5219.2 5368.4 5369.8 5388.6 5398.7 4147.9 4297.9 5335.4 5386.2 5464.9 5459.3 5468.5 5477.7 5482.8 5487.4 11:01:56 INFO - PROCESS | 1653 | 11:01:56 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:19485.26 Median:4394.35 stddev:28198.58 (641.7%) stddev-sans-first:28242.33 11:01:56 INFO - PROCESS | 1653 | Values: 679.9 704.8 708.8 725.4 763.0 14072.9 14649.5 14650.6 14692.6 14703.7 12932.1 13048.3 13061.6 13064.9 13074.5 820.5 823.0 834.7 837.9 839.6 841.0 841.7 842.0 842.9 844.0 888.7 889.2 890.7 893.7 896.1 875.5 879.5 879.8 880.5 882.7 657.5 658.3 659.3 661.4 661.7 12258.8 12306.8 12505.2 12549.6 12551.5 10891.9 10904.7 11480.1 11608.7 11795.0 10452.0 10928.1 11075.3 11109.0 11509.7 4319.3 4372.5 4393.7 4395.0 4534.5 76868.0 86640.6 88369.7 89911.0 90770.2 8665.9 10121.2 10297.0 10314.2 10326.3 18992.6 19031.8 19120.9 19230.0 19240.1 6783.8 6888.2 7231.7 7528.4 7541.0 4026.5 4034.4 4151.9 4273.1 4327.8 2835.0 3262.1 3328.1 3335.4 3344.3 545.9 550.3 579.8 608.9 636.3 574.4 584.2 590.9 604.3 606.9 7941.8 8720.9 8747.8 8770.9 8877.9 533.8 588.8 617.1 624.1 630.3 3945.9 4032.7 4187.5 4313.2 4341.4 97677.0 102718.5 104148.5 104377.0 106777.0 62887.0 64765.0 73963.3 93435.0 99733.5 83612.3 85096.0 87213.0 87233.1 91686.0 9159.0 9616.5 9651.0 9654.6 9668.2 51610.5 53021.0 53115.5 53292.7 54613.7 44130.0 65144.3 72592.0 72807.0 75306.2 12109.7 12269.9 12368.8 12430.7 12902.7 13366.0 13698.5 13990.2 14053.3 14350.2 12440.8 12643.6 12753.9 13021.3 13414.5 12829.8 13012.7 13041.6 13270.9 13384.3 12417.8 12805.3 13249.9 13434.9 13470.3 10454.6 10850.0 10853.1 11504.1 11538.0 10388.9 10955.1 11212.0 11270.1 11341.7 11:01:56 INFO - PROCESS | 1653 | -------- Summary: end -------- 11:01:56 INFO - PROCESS | 1653 | 11:01:57 INFO - TEST-INFO | 1653: exit 0 11:01:57 INFO - TEST-OK | dromaeo_css | took 1119700ms 11:01:57 INFO - TEST-START | kraken 11:01:57 INFO - operating with platform_type : mac_ 11:01:57 INFO - Initialising browser for kraken test... 11:01:57 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmptoRxvl/profile http://localhost:49275/getInfo.html) 11:01:58 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 11:01:59 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 11:01:59 INFO - PROCESS | 1664 | colorDepth:24 11:01:59 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 11:01:59 INFO - PROCESS | 1664 | __metrics 11:01:59 INFO - PROCESS | 1664 | 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" 11:01:59 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:02:00 INFO - TEST-INFO | 1664: exit 0 11:02:00 INFO - Browser initialized. 11:02:00 INFO - Running cycle 1/1 for kraken test... 11:02:00 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmptoRxvl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:02:12 INFO - PROCESS | 1665 | RSS: Main: 217686016 11:02:12 INFO - PROCESS | 1665 | 11:02:15 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 11:02:16 INFO - PROCESS | 1665 | RSS: Main: 264937472 11:02:16 INFO - PROCESS | 1665 | 11:02:19 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 11:02:19 INFO - PROCESS | 1665 | RSS: Main: 277458944 11:02:19 INFO - PROCESS | 1665 | 11:02:23 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 11:02:23 INFO - PROCESS | 1665 | RSS: Main: 374571008 11:02:23 INFO - PROCESS | 1665 | 11:02:25 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 11:02:26 INFO - PROCESS | 1665 | RSS: Main: 278626304 11:02:26 INFO - PROCESS | 1665 | 11:02:28 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:02:29 INFO - PROCESS | 1665 | RSS: Main: 280379392 11:02:29 INFO - PROCESS | 1665 | 11:02:32 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 11:02:32 INFO - PROCESS | 1665 | RSS: Main: 320000000 11:02:32 INFO - PROCESS | 1665 | 11:02:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 11:02:36 INFO - PROCESS | 1665 | RSS: Main: 319946752 11:02:36 INFO - PROCESS | 1665 | 11:02:38 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 11:02:39 INFO - PROCESS | 1665 | RSS: Main: 318107648 11:02:39 INFO - PROCESS | 1665 | 11:02:41 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:02:41 INFO - PROCESS | 1665 | RSS: Main: 295591936 11:02:41 INFO - PROCESS | 1665 | 11:02:43 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:02:44 INFO - PROCESS | 1665 | RSS: Main: 296370176 11:02:44 INFO - PROCESS | 1665 | 11:02:46 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:02:47 INFO - PROCESS | 1665 | RSS: Main: 306204672 11:02:47 INFO - PROCESS | 1665 | 11:02:50 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:02:51 INFO - PROCESS | 1665 | RSS: Main: 310444032 11:02:51 INFO - PROCESS | 1665 | 11:02:53 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:02:54 INFO - PROCESS | 1665 | RSS: Main: 310267904 11:02:54 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 11:02:56 INFO - PROCESS | 1665 | RSS: Main: 353574912 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | __start_tp_report 11:02:56 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 11:02:56 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 11:02:56 INFO - PROCESS | 1665 | |i|pagename|runs| 11:02:56 INFO - PROCESS | 1665 | |0;ai-astar;100;112;120;168;159;112;113;112;112;110 11:02:56 INFO - PROCESS | 1665 | |1;audio-beat-detection;142;111;121;179;142;123;111;112;141;110 11:02:56 INFO - PROCESS | 1665 | |2;audio-dft;183;183;184;184;204;263;200;186;184;190 11:02:56 INFO - PROCESS | 1665 | |3;audio-fft;81;60;77;114;84;84;60;59;82;60 11:02:56 INFO - PROCESS | 1665 | |4;audio-oscillator;86;72;123;101;98;68;89;68;89;75 11:02:56 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;88;84;87;84;87;83;91;131;87;83 11:02:56 INFO - PROCESS | 1665 | |6;imaging-darkroom;143;105;92;92;93;94;93;93;93;92 11:02:56 INFO - PROCESS | 1665 | |7;imaging-desaturate;80;81;81;82;83;81;82;84;80;84 11:02:56 INFO - PROCESS | 1665 | |8;json-parse-financial;58;56;56;57;57;58;58;57;58;58 11:02:56 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;70;75;73;71;73;69;94;102;102;77 11:02:56 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;89;86;84;89;89;83;87;89;94;93 11:02:56 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;146;148;148;146;145;145;146;181;221;156 11:02:56 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;166;164;170;167;169;167;168;166;166;165 11:02:56 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;68;67;67;67;67;67;69;72;87;87 11:02:56 INFO - PROCESS | 1665 | __end_tp_report 11:02:56 INFO - PROCESS | 1665 | __start_cc_report 11:02:56 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2039 11:02:56 INFO - PROCESS | 1665 | __end_cc_report 11:02:56 INFO - PROCESS | 1665 | __startTimestamp1479150176602__endTimestamp 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | ------- Summary: start ------- 11:02:56 INFO - PROCESS | 1665 | Number of tests: 14 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:121.80 Median:112.50 stddev:22.60 (20.1%) stddev-sans-first:22.55 11:02:56 INFO - PROCESS | 1665 | Values: 100.0 112.0 120.0 168.0 159.0 112.0 113.0 112.0 112.0 110.0 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:132.00 stddev:22.06 (16.7%) stddev-sans-first:22.91 11:02:56 INFO - PROCESS | 1665 | Values: 142.0 111.0 121.0 179.0 142.0 123.0 111.0 112.0 141.0 110.0 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:196.10 Median:188.00 stddev:24.66 (13.1%) stddev-sans-first:25.70 11:02:56 INFO - PROCESS | 1665 | Values: 183.0 183.0 184.0 184.0 204.0 263.0 200.0 186.0 184.0 190.0 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:76.10 Median:79.00 stddev:17.29 (21.9%) stddev-sans-first:18.25 11:02:56 INFO - PROCESS | 1665 | Values: 81.0 60.0 77.0 114.0 84.0 84.0 60.0 59.0 82.0 60.0 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:86.90 Median:80.50 stddev:17.36 (21.6%) stddev-sans-first:18.41 11:02:56 INFO - PROCESS | 1665 | Values: 86.0 72.0 123.0 101.0 98.0 68.0 89.0 68.0 89.0 75.0 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:87.00 stddev:14.45 (16.6%) stddev-sans-first:15.30 11:02:56 INFO - PROCESS | 1665 | Values: 88.0 84.0 87.0 84.0 87.0 83.0 91.0 131.0 87.0 83.0 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:99.00 Median:93.00 stddev:15.94 (17.1%) stddev-sans-first:4.14 11:02:56 INFO - PROCESS | 1665 | Values: 143.0 105.0 92.0 92.0 93.0 94.0 93.0 93.0 93.0 92.0 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:81.80 Median:82.00 stddev:1.48 (1.8%) stddev-sans-first:1.41 11:02:56 INFO - PROCESS | 1665 | Values: 80.0 81.0 81.0 82.0 83.0 81.0 82.0 84.0 80.0 84.0 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:58.00 stddev:0.82 (1.4%) stddev-sans-first:0.83 11:02:56 INFO - PROCESS | 1665 | Values: 58.0 56.0 56.0 57.0 57.0 58.0 58.0 57.0 58.0 58.0 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:80.60 Median:73.00 stddev:13.31 (18.2%) stddev-sans-first:13.55 11:02:56 INFO - PROCESS | 1665 | Values: 70.0 75.0 73.0 71.0 73.0 69.0 94.0 102.0 102.0 77.0 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:89.00 stddev:3.50 (3.9%) stddev-sans-first:3.70 11:02:56 INFO - PROCESS | 1665 | Values: 89.0 86.0 84.0 89.0 89.0 83.0 87.0 89.0 94.0 93.0 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:158.20 Median:148.00 stddev:24.66 (16.7%) stddev-sans-first:25.75 11:02:56 INFO - PROCESS | 1665 | Values: 146.0 148.0 148.0 146.0 145.0 145.0 146.0 181.0 221.0 156.0 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:167.00 stddev:1.81 (1.1%) stddev-sans-first:1.90 11:02:56 INFO - PROCESS | 1665 | Values: 166.0 164.0 170.0 167.0 169.0 167.0 168.0 166.0 166.0 165.0 11:02:56 INFO - PROCESS | 1665 | 11:02:56 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:68.50 stddev:8.16 (11.9%) stddev-sans-first:8.54 11:02:56 INFO - PROCESS | 1665 | Values: 68.0 67.0 67.0 67.0 67.0 67.0 69.0 72.0 87.0 87.0 11:02:56 INFO - PROCESS | 1665 | -------- Summary: end -------- 11:02:56 INFO - PROCESS | 1665 | 11:02:57 INFO - TEST-INFO | 1665: exit 0 11:02:57 INFO - TEST-OK | kraken | took 60191ms 11:02:57 INFO - SUITE-END | took 1179s 11:02:57 INFO - Completed test suite (00:19:40) 11:02:57 INFO - javascript benchmark 11:02:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3247.9999999999995, 3266.28141808802, 3317.8672853085877, 3327.584051993501, 3333.100062982993, 5657.0, 5740.339860915986, 5761.395053519378, 5762.337331206907, 5783.392743761897, 5727.398623144572, 5783.884322313551, 5789.189513468118, 5793.420657934204, 5794.71026448678, 5203.713795741232, 5207.817726379578, 5211.478852114787, 5254.947450525503, 5262.0, 5128.307678463416, 5134.204198349262, 5303.071962406582, 5315.867103322428, 5367.919481207766, 5188.870278243036, 5227.3465816772905, 5234.6859188448625, 5247.212918062283, 5248.134057880445, 5226.895462090756, 5251.949610078, 5269.894602107936, 5272.630915835893, 5278.47215278473, 5081.364829396325, 5194.220866869962, 5205.115130427836, 5213.635045546814, 5242.18746094356, 5738.196652468658, 5750.683712395817, 5753.712314384264, 5757.453041961007, 5759.280089988752, 6167.383261673796, 6181.505479561669, 6188.535859810488, 6196.977498712707, 6198.783042593533, 6043.274807023141, 6263.812085637438, 6289.842753931142, 6301.369863013708, 6303.401176888188, 4793.7363445010515, 5732.398098199712, 5755.165501002373, 5760.654360738369, 5807.0, 6262.5929314594405, 6298.7480500780375, 6300.243970723543, 6312.905306420407, 6320.304766475684, 5989.251343582052, 6041.305249896231, 6095.756169753249, 6114.174586430775, 6135.845176233341, 6186.10301506279, 6257.843553911189, 6264.467520260829, 6303.653299068504, 6324.367563243731, 6219.129321894939, 6257.468115210167, 6266.091416744546, 6284.874302513924, 6294.622322660655, 6112.1995365628145, 6154.415330543591, 6176.444120029129, 6183.752649893954, 6263.0, 4914.533119353656, 4935.481774413634, 4967.478414766398, 4978.5270399312, 4999.800007999712, 4455.6212721918355, 4458.175237581057, 4460.130274596422, 4484.573965473275, 4485.6860019798305, 5733.226014487991, 5756.5682573807135, 5818.330891947396, 5843.211166492469, 5855.092460668603, 6199.535034872402, 6254.124422580842, 6297.748090076346, 6312.621242725451, 6332.398422149889, 5794.449527294901, 5800.854978625568, 5830.737616807254, 5841.591088623755, 5873.0, 5312.203169524602, 5450.127979523258, 5458.536024437967, 5468.72656367188, 5470.56235501159, 6221.813345599639, 6233.407826256498, 6246.500279977682, 6247.656378899204, 6302.65335406557, 6314.147590075281, 6315.778947736807, 6316.14732011191, 6317.147185130132, 6317.7157027932935, 6295.842603934938, 6314.968425158028, 6331.810045698637, 6338.651374174465, 6344.397282258178, 6033.155358249936, 6047.516198704181, 6112.847178820387, 6145.8156255312415, 6246.0, 5352.956173546121, 5915.881682366243, 6233.75064997395, 6316.021016742412, 6336.619802811846, 6205.47253483459, 6208.751649933952, 6210.378962103753, 6232.096346029891, 6236.376362363727, 6002.369751176064, 6121.602095863755, 6208.658523781055, 6212.223472065992, 6238.625682459067, 5865.912011319919, 5904.0, 5921.289445266596, 5935.643861368331, 6096.786612468542, 5982.910256346071, 6154.323024467394, 6156.479679934813, 6165.0444181152, 6190.0, 5850.327212370632, 5879.412058794086, 5933.703314834327, 5958.910616340671, 6034.275886893425], "value": 5715.005464118673, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.387101284279, 524.8609118583579, 532.0715351711265, 554.9139883318087, 582.9300483941927, 35178.120546986276, 38862.611373886255, 39222.41166382506, 39955.801325960194, 41268.0, 40825.0, 41241.793791031, 41260.58739412605, 41281.79359103208, 41326.380104298456, 9225.446473211612, 9363.812723745521, 9392.295577831655, 9407.717768466957, 9413.81172376552, 9248.491332976684, 9343.71968840936, 9345.95327023364, 9354.017828128051, 9368.156865882069, 9358.57886395114, 9406.43561386319, 9422.62309507619, 9424.104710052534, 9424.34029617927, 9312.0, 9356.298277629137, 9365.250779937656, 9366.625334986593, 9383.859242111408, 9192.080791920822, 9277.768055798591, 9282.675106371313, 9287.349885508018, 9296.674616388394, 38756.03109922246, 39231.80384098061, 39307.803460982796, 39334.60665393338, 39371.212575748614, 3271.1495011296997, 3373.443381841993, 3397.932041359159, 3677.9448308275396, 3803.9809800951093, 27440.557073670807, 28344.433111337865, 29635.259118521994, 30469.847650761825, 30847.69152308493, 2862.912093404493, 3157.2422618571377, 3160.225744692542, 3219.146926064595, 3292.670732926712, 499805.9999999964, 499973.0, 500598.0, 500899.0, 502445.0, 27464.03875864335, 57323.713381432826, 57870.71064644734, 57907.42092579105, 57955.0, 32874.67125328764, 32961.83519082389, 34170.0, 34207.0, 34211.31577368489, 14262.57212283633, 14364.640883977776, 14381.352839122264, 14424.278786060655, 14458.421663133357, 11996.820047699292, 12088.697782555331, 12093.516259349528, 12099.879001209874, 12114.0, 7662.080550333884, 7679.385649148055, 7729.8454030919065, 7738.187490313549, 7746.264104910025, 3153.936921261562, 3197.3125777957853, 3197.344544368399, 3201.231704390976, 3233.919152021171, 824.2952275804221, 872.882160908282, 903.336048004716, 939.145377706284, 1011.6509804117715, 28747.85626071898, 29124.563131553045, 29208.0, 29929.70070299269, 30168.0, 849.2781136034445, 1028.8919663435381, 1029.4235228272041, 1065.6589891234885, 1066.504075604832, 13122.475100996044, 13241.3379331035, 14795.668389844966, 15063.548093556992, 15184.544463665887, 706907.0, 709034.0, 709668.0, 713103.0, 713763.0, 55078.44921550733, 55590.72204638951, 55666.443335566124, 55725.0, 55770.72114639482, 1353.9932300338633, 1354.4040622126233, 1385.7228554289181, 1385.8267716535433, 1387.5213051497217, 23595.646065309378, 27546.86226568854, 28284.434311313245, 28568.022294084847, 28763.0, 47867.76066119647, 48489.51510484991, 48499.75750121227, 48504.02991940212, 48511.27233091436, 615793.0, 615855.0, 616992.0, 617520.9123954531, 620661.0, 25622.69395754391, 25797.871010644827, 25891.61162582525, 26021.0, 43969.12061758811, 47180.0, 47541.0, 48273.0, 49019.26471102865, 49019.50980490149, 39253.0, 39634.810955671375, 40022.59977400189, 40265.59734402619, 41618.167636647704, 37693.0, 38377.232455350175, 39091.0, 39783.60216397799, 40271.0, 39005.609943901334, 39028.609713903636, 39174.0, 39747.80126099351, 39962.400563992145, 33132.1716957078, 34146.829265853514, 35207.82396088003, 35249.64750352463, 35304.64695353116, 22173.780442075196, 25437.87281063583, 25887.741122588533, 26045.277485449024, 26238.60642090332], "value": 18447.92333157173, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.29098094791374, 369.09939747017273, 372.67018688460706, 388.8930544100373, 408.77517365448995, 14445.783313250296, 14561.854381456182, 15287.70684397341, 19429.222831086776, 19989.900050499728, 15829.366825327003, 18551.07244637766, 18858.528536786587, 19348.51628709283, 19608.0, 5661.179129026289, 5969.552283578727, 5970.0, 5979.342272350038, 5983.58114931955, 5845.5908086433965, 6137.723802428879, 6489.383508566702, 6557.442617377528, 6645.136132302794, 5953.613015154023, 6567.868642627144, 6575.506836987245, 6578.703958321887, 6606.14120164378, 5931.584789064767, 6040.0939859021055, 6061.484773794233, 6062.514998800108, 6062.818115456544, 5637.689927054011, 5835.328937172195, 5870.589058765888, 5870.853228669274, 5907.172995780594, 13093.279869607262, 13304.733905321937, 13369.064165508418, 13398.933005334911, 24874.25377238686, 2239.0595949701164, 2263.2644390572964, 2324.8721320327377, 2339.0760649543336, 2454.8895299711558, 9527.333086683935, 14437.71124577513, 15024.624384390369, 15359.924805263636, 15360.078395296317, 1606.36548563318, 1742.5295170303948, 1785.3309302592577, 1820.708686610136, 1860.9534761630932, 86166.0, 109529.0, 110364.0, 122926.0, 123865.0, 9289.953550232342, 11097.0, 11612.12909031826, 11715.824262636068, 11790.764184716429, 14687.485937992333, 14742.631434214016, 14788.260586970824, 14797.482088127079, 14848.331825067899, 8193.67225310981, 8490.533020683923, 8495.872561911576, 8562.0, 8568.400211985227, 7578.810529736801, 7661.310482056641, 8264.669413223524, 8278.130796266425, 8448.619812108469, 4368.497622773358, 4443.289073748249, 4523.072770082125, 4584.77076146198, 4621.32990716351, 1697.73685078813, 1873.8101305670998, 1904.3620387170176, 1905.9047047647562, 1923.1634239106033, 814.0, 899.5142622983647, 962.2157941277836, 965.5413678502655, 992.2910454459222, 20204.48344443653, 20777.480562985744, 21753.0, 22345.73988574619, 22717.2048978285, 861.3970220845433, 938.9953050234706, 956.2541217850088, 958.324381311174, 971.7182017214928, 9892.653757118467, 10689.30519516229, 10763.977422144884, 11178.385188814693, 11379.601713939972, 625090.0, 633068.0, 635625.0, 640912.0, 646412.0, 124350.0, 126178.36910815387, 127883.36058319834, 138298.0, 142791.2860435712, 977.1449981266392, 984.1388784813288, 1026.1790567545934, 1026.2610920137488, 1028.922830787694, 16064.919675401548, 18513.53716157107, 18695.626087478446, 18871.433856984313, 19447.416577502696, 35825.64174358223, 49557.00885982332, 50920.49079509157, 52753.20870187027, 54788.0, 79467.10493550614, 79470.20529794628, 80078.5996070016, 80895.12391446557, 83337.58331208548, 7946.205379462008, 7958.602069896598, 8046.637901294339, 8173.610486217239, 8178.0, 10689.51897164645, 11591.826122608336, 11824.23142495735, 12039.157258991787, 12678.936605316914, 10494.947525262325, 10524.158067354747, 10741.227697429837, 10817.350958942488, 11073.667789966314, 10558.524866381185, 10590.735231618963, 10734.892651073702, 10747.193960452842, 10850.94574527154, 10547.947260263649, 10710.946445267724, 10794.244402891722, 10836.078933290759, 11034.944825275823, 7894.250046245597, 7909.802254943443, 8147.877781833158, 8284.171582841667, 8327.292180164754, 10109.595616175271, 10641.574337026432, 10790.73023174427, 10857.13142948578, 10961.287516311415], "value": 9418.368649619992, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1339.1764065099953, 1402.8386735525416, 1481.1261355800075, 1643.6876993371266, 1839.6872531669605, 7619.542827430351, 7993.360531157508, 8004.319632831209, 8312.293455056328, 8326.625301861415, 8204.753857384274, 8341.165883411655, 8367.037790654076, 8372.330213582914, 8375.329973602098, 2298.2071185441037, 2338.1816364272454, 2341.0401735288533, 2343.4024323797407, 2343.6484527320868, 2323.6398358254496, 2358.7287461941924, 2375.3824005758543, 2380.654805053266, 2388.379021454417, 2277.191596983072, 2285.200179937017, 2288.1533832482005, 2313.0516488239823, 2336.100601268507, 1962.2347284559035, 2274.977250227493, 2286.931392058241, 2312.0636142362287, 2312.479692069271, 2304.0783686525356, 2320.33870346952, 2323.6514522821544, 2325.5697695926137, 2327.6392159215347, 8289.378296627778, 8341.45780524264, 8361.623726932241, 8372.455790373606, 8372.623231954576, 5014.598832093424, 5035.949640503584, 5219.6085293602755, 5227.79088836446, 5228.660137091115, 3015.4873671475684, 3470.854617976062, 3499.842507087187, 3557.839897204632, 3583.835253542583, 1044.2794471814423, 1054.2000553309413, 1081.6538707613568, 1085.5766251162054, 1099.7855418193456, 10886.455677216187, 11079.944600276947, 11095.55617775288, 11117.166212534012, 11171.329720216812, 4513.81944722214, 4604.700694454849, 4702.57676809082, 4705.200115980291, 4710.9764451177525, 9559.139677429064, 9562.995885432068, 9569.473678947725, 9582.233421326277, 9589.760255993517, 5218.869528261824, 5240.161574148118, 5242.580593552507, 5245.344331958505, 5260.9736951315, 4206.936895946563, 4339.067100573392, 4569.885752856205, 4689.3903792507435, 4711.0342379812055, 3758.1732018955786, 3814.7520411173186, 3843.9807800960816, 3891.5330160380936, 3903.902402439962, 1061.9522121504551, 1243.483954159032, 1257.69186549296, 1269.6318067760246, 1276.4511260158222, 799.8400319936036, 887.8978917424579, 896.4173287363266, 903.9050899655441, 910.4264313482464, 5401.950454656632, 5452.427495113036, 5549.139883318092, 5690.231818704505, 5768.192453056576, 976.9853452198223, 1006.5520843224841, 1011.9190464762802, 1017.6692574913183, 1041.4376236832177, 3572.142685755452, 3588.9102772430374, 3708.128589781399, 3771.0383852118084, 3881.5536213335827, 10011.299209055442, 10223.0, 10380.42907640072, 10393.16854651626, 10397.48012599367, 10311.48442577868, 10333.328333658288, 10335.586576537005, 10339.276250662382, 10350.689479315633, 7570.432217583703, 7777.72777952769, 8041.959790201128, 8059.919400806034, 8064.798380040428, 4379.474463064453, 5523.171524271392, 5568.220449137043, 5860.443257890493, 5866.325372582208, 9151.771205719644, 9429.15137637616, 9439.197668198005, 9464.432134071978, 9627.518624068629, 9342.673006444742, 9619.855702164334, 9652.083052110038, 9753.561089750836, 9758.75603109928, 7050.08720160316, 7129.322714342129, 7217.566945983258, 7243.022192004223, 7253.0, 7746.147923728498, 7798.805029874298, 7812.1797211292, 7812.296893279632, 7817.0, 6886.51794374389, 6942.132233470817, 7062.3996960258955, 7167.139943206848, 7194.856102877809, 6970.830814729103, 7037.120359955005, 7068.363847253772, 7201.099862517185, 7238.27617238272, 6628.502862285444, 6799.11611490503, 6801.795946121624, 6808.829779255559, 6812.693428795617, 4722.409698787652, 4790.712557246577, 4836.419629644465, 4868.537488938545, 4922.0, 4823.300621409946, 4824.662273640812, 4847.951520484891, 5003.199488081889, 5011.047900898818], "value": 4438.873886153078, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.60705851810064, 305.5081319076288, 309.2994367757031, 314.3916521530838, 315.3582459694522, 5269.420363759983, 5289.365276166861, 5329.25390445337, 5364.0, 5405.810796622114, 4472.44094488189, 4564.748938808372, 4625.011245164578, 4973.577245934091, 5212.947870521293, 3987.7407968482103, 3987.900302492432, 3990.5211374635082, 4028.6777057835448, 4068.4507591475217, 3980.3432433648413, 3987.9800600996955, 3998.600139985993, 4024.879253622396, 4042.3532234842432, 3296.3077753671637, 3494.9340451162384, 3761.514201890265, 4002.919941601166, 4011.59884011598, 3462.9134271643156, 3959.1685745993377, 3961.3859851723028, 3967.127232008968, 3987.7407968482103, 3461.7671601816937, 3470.444728843373, 3474.2009337852437, 3486.9302613947834, 3532.2405682778067, 4189.958100418988, 5033.471485494044, 5039.370078740158, 5042.470540593222, 5046.6719663221775, 3794.810259487042, 4300.763458009808, 4537.841175558872, 4540.477845047829, 4607.516210797885, 4555.316702494652, 5399.0, 5412.4858138477175, 5444.156155795858, 5479.835604931858, 3293.0, 3822.2164456286396, 3823.8279277432302, 3880.631340022693, 3895.7662540247675, 5168.121419358643, 5500.4224556421395, 5713.828665123681, 5752.309722833287, 5752.683602401867, 3751.4185301278344, 3879.18537107205, 3879.670228030649, 3880.8835734927998, 3881.941770873439, 3621.257642183346, 3680.7485454945445, 3970.622790834866, 3983.1635356574866, 3997.6202260785763, 3917.5494818095713, 4400.471943366816, 4558.794854231567, 4627.467841198305, 4654.743989080565, 4780.378550788375, 5042.621803364762, 5443.374011988592, 5487.314085739283, 5587.0, 1260.848698156227, 1273.7643535945879, 1281.8205451236834, 1286.2154086007529, 1286.7040580666312, 670.510527315053, 839.085396917363, 843.8144407108023, 867.800405906645, 888.8666699994923, 4105.91788164241, 4174.812133454002, 4338.522762496123, 4788.95211047892, 4814.711117332971, 4655.930161047587, 5232.148479078911, 5249.370075590878, 5264.289106040552, 5268.470125016248, 3936.3308237599676, 4280.587406155961, 4563.201439748031, 4825.831095911626, 4863.540937718741, 2762.392273699783, 2783.359827239746, 2806.5790131480035, 2813.6084328320762, 2822.957655635167, 5287.41838397776, 5441.183822426668, 5532.446755324516, 5545.417731138253, 5555.972220138954, 4587.747673877902, 4923.606111511071, 5338.412774594792, 5416.916616676681, 5473.223475801684, 4851.870543499424, 5185.585153187736, 5224.582033437392, 5474.069408200616, 5476.890462190813, 4399.392498151485, 4931.0630980113665, 5288.735563221747, 5324.787008519771, 5344.430511912259, 4498.415206023196, 4866.68599478146, 4943.8516844494725, 5262.842114736564, 5306.787728490817, 5112.795488180431, 5281.563486671214, 5354.303940487712, 5462.289902312674, 5551.084071128219, 3317.3199494104133, 3706.5366829146355, 3729.3846515324667, 3770.699108807457, 3776.546814382292, 4481.865544033684, 4570.245909424908, 5234.906037584844, 5634.211210430625, 5647.407022262603, 5280.60395470349, 5414.29614150158, 5491.862703432446, 5530.6128570999645, 5607.495325420732, 5205.609579281493, 5602.030923610493, 5623.847111342083, 5650.542014223825, 5665.801696940587, 5219.243209734643, 5368.436314186954, 5369.785208591768, 5388.595855310789, 5398.678568215705, 4147.917041659211, 4297.935530966975, 5335.359756829205, 5386.245925570502, 5464.93433780409, 5459.317585301837, 5468.507834294774, 5477.67133971963, 5482.77947431291, 5487.368952570506], "value": 3928.0347022099613, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.8708245433368, 704.788563430971, 708.8475977664801, 725.4232884856539, 763.0233301374241, 14072.859271407297, 14649.487267945597, 14650.560483185509, 14692.632684182874, 14703.70592588149, 12932.094753367268, 13048.347582620854, 13061.60815175544, 13064.93467532661, 13074.542391016317, 820.4748960665164, 823.0288259853371, 834.6744769539885, 837.8659414493683, 839.6389552492426, 840.9663613455455, 841.7222316635496, 842.0148426341195, 842.9199226073544, 843.9998601657056, 888.6800751729372, 889.1641856654757, 890.6526454682679, 893.7229458867772, 896.135229503529, 875.4528419737663, 879.4679219072465, 879.7800549862567, 880.5113162194979, 882.668999125328, 657.5265808617787, 658.3219284137347, 659.3176062775004, 661.3683931845077, 661.675778868351, 12258.754824903452, 12306.75386492274, 12505.249685018836, 12549.623511294585, 12551.497940082385, 10891.856355082682, 10904.672859814218, 11480.138989575815, 11608.6517404478, 11794.997425218866, 10452.0, 10928.071113955324, 11075.280106793032, 11109.0, 11509.712257193638, 4319.308910574325, 4372.519022907478, 4393.692441529094, 4395.0, 4534.501204867462, 76868.0, 86640.56679716625, 88369.67445488322, 89911.0, 90770.18459630836, 8665.913340866511, 10121.24090693201, 10297.02178293061, 10314.1748660107, 10326.277160598685, 18992.62014759725, 19031.809681903003, 19120.90439547821, 19230.0, 19240.134193961025, 6783.796486105425, 6888.242293347727, 7231.7107315707835, 7528.360089392353, 7541.0, 4026.53694825093, 4034.3948407738485, 4151.875443736692, 4273.059926816096, 4327.783610819446, 2835.0219174384806, 3262.0866157475944, 3328.1014126185796, 3335.4163021471145, 3344.347852168804, 545.939946605873, 550.3395924890148, 579.7709904587748, 608.8538750699888, 636.3191385217865, 574.4255744255744, 584.1938125386939, 590.8906852232265, 604.2748701558148, 606.9180660610849, 7941.761747147595, 8720.91279087201, 8747.781305467286, 8770.868436973451, 8877.866831997526, 533.815833537429, 588.7556663984484, 617.0836308082494, 624.0919462182513, 630.3035146163515, 3945.92108157841, 4032.7177097602885, 4187.539370669224, 4313.223619748475, 4341.435613370241, 97677.0, 102718.48640756749, 104148.47925760323, 104377.0, 106777.0, 62887.0, 64765.02852457217, 73963.26036739671, 93435.0, 99733.50133249287, 83612.31101511864, 85096.0, 87213.0, 87233.1276687225, 91686.0, 9158.95420522893, 9616.471094089893, 9651.0, 9654.613815447303, 9668.226541876773, 51610.48389516057, 53021.0, 53115.46884531105, 53292.73353633207, 54613.72693136509, 44130.0, 65144.348556515724, 72592.0, 72807.0, 75306.24693752993, 12109.69725756863, 12269.93865030669, 12368.814467782811, 12430.689232769253, 12902.741945161231, 13366.0, 13698.452061917811, 13990.230537320545, 14053.297335133408, 14350.210738409487, 12440.751184976069, 12643.557475488315, 12753.936230318788, 13021.348932553525, 13414.530491432753, 12829.807552886901, 13012.672497762718, 13041.608751737464, 13270.933645331712, 13384.330783460982, 12417.751644967231, 12805.295708735735, 13249.933750331187, 13434.93282533581, 13470.32648367558, 10454.581816727246, 10850.0, 10853.131749460183, 11504.137189710638, 11538.0, 10388.896111039096, 10955.120144946202, 11211.98184326829, 11270.098392128773, 11341.659750207507], "value": 7099.184266165099, "unit": "score"}], "name": "dromaeo_css", "value": 7051.37281047726, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 120.0, 168.0, 159.0, 112.0, 113.0, 112.0, 112.0, 110.0], "value": 121.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 111.0, 121.0, 179.0, 142.0, 123.0, 111.0, 112.0, 141.0, 110.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 184.0, 184.0, 204.0, 263.0, 200.0, 186.0, 184.0, 190.0], "value": 196.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 77.0, 114.0, 84.0, 84.0, 60.0, 59.0, 82.0, 60.0], "value": 76.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 123.0, 101.0, 98.0, 68.0, 89.0, 68.0, 89.0, 75.0], "value": 86.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 84.0, 87.0, 83.0, 91.0, 131.0, 87.0, 83.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 105.0, 92.0, 92.0, 93.0, 94.0, 93.0, 93.0, 93.0, 92.0], "value": 99.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 81.0, 82.0, 83.0, 81.0, 82.0, 84.0, 80.0, 84.0], "value": 81.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 56.0, 57.0, 57.0, 58.0, 58.0, 57.0, 58.0, 58.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 73.0, 71.0, 73.0, 69.0, 94.0, 102.0, 102.0, 77.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 84.0, 89.0, 89.0, 83.0, 87.0, 89.0, 94.0, 93.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 148.0, 146.0, 145.0, 145.0, 146.0, 181.0, 221.0, 156.0], "value": 158.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 170.0, 167.0, 169.0, 167.0, 168.0, 166.0, 166.0, 165.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 67.0, 67.0, 67.0, 69.0, 72.0, 87.0, 87.0], "value": 71.8, "unit": "score"}], "name": "kraken", "value": 1504.3999999999999, "alertThreshold": 2.0}]} 11:02:57 INFO - Return code: 0 11:02:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:02:57 ERROR - # TBPL SUCCESS # 11:02:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:02:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:02:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:02:57 INFO - 2016-11-14 11:02:57.920 screenresolution[1669:9925] starting screenresolution argv=screenresolution get 11:02:57 INFO - 2016-11-14 11:02:57.923 screenresolution[1669:9925] Display 0: 1600x1200x32@60 11:02:57 INFO - 2016-11-14 11:02:57.929 screenresolution[1670:9928] starting screenresolution argv=screenresolution list 11:02:57 INFO - Available Modes on Display 0 11:02:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:02:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:02:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:02:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:02:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:02:58 INFO - Intel Iris: 11:02:58 INFO - Chipset Model: Intel Iris 11:02:58 INFO - Type: GPU 11:02:58 INFO - Bus: Built-In 11:02:58 INFO - VRAM (Dynamic, Max): 1536 MB 11:02:58 INFO - Vendor: Intel (0x8086) 11:02:58 INFO - Device ID: 0x0a2e 11:02:58 INFO - Revision ID: 0x0009 11:02:58 INFO - Displays: 11:02:58 INFO - Display: 11:02:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:02:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:02:58 INFO - Display Serial Number: 1600x1200 60 11:02:58 INFO - Main Display: Yes 11:02:58 INFO - Mirror: Off 11:02:58 INFO - Online: Yes 11:02:58 INFO - Rotation: Supported 11:02:58 INFO - Return code: 0 11:02:58 INFO - Running post-action listener: _resource_record_post_action 11:02:58 INFO - [mozharness: 2016-11-14 19:02:58.165610Z] Finished run-tests step (success) 11:02:58 INFO - Running post-run listener: _resource_record_post_run 11:02:58 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38933504; Write bytes: 652978176; Read time: 383; Write time: 1763 11:02:58 INFO - TinderboxPrint: CPU usage
53.5% 11:02:58 INFO - TinderboxPrint: I/O read bytes / time
38,933,504 / 383 11:02:58 INFO - TinderboxPrint: I/O write bytes / time
652,978,176 / 1,763 11:02:58 INFO - TinderboxPrint: CPU idle
2,253.5 (46.5%) 11:02:58 INFO - TinderboxPrint: CPU system
972.3 (20.0%) 11:02:58 INFO - TinderboxPrint: CPU user
1,623.9 (33.5%) 11:02:58 INFO - TinderboxPrint: Swap in / out
391,708,672 / 0 11:02:58 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 343089664; Write bytes: 336910336; Read time: 26515; Write time: 616 11:02:58 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 37983232; Write bytes: 297500672; Read time: 369; Write time: 1094 11:02:58 INFO - Running post-run listener: _upload_blobber_files 11:02:58 INFO - Blob upload gear active. 11:02:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:02:58 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 11:02:58 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'] 11:02:58 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 11:02:59 INFO - (blobuploader) - INFO - Open directory for files ... 11:02:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:02:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:02:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:03:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:03:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:03:00 INFO - (blobuploader) - INFO - Done attempting. 11:03:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:03:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:03:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:03:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:03:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:03:01 INFO - (blobuploader) - INFO - Done attempting. 11:03:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:03:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:03:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:03:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:03:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:03:03 INFO - (blobuploader) - INFO - Done attempting. 11:03:03 INFO - (blobuploader) - INFO - Iteration through files over. 11:03:03 INFO - Return code: 0 11:03:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:03:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:03:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed0ca3e2d3d7b6d0c4f0d74989ca8af3eb3ff1892fa7b1d1eaa6752a15696f43791c0da9a08f9e58baaed1cbf6c2b3b15ba3889126f9d8d9b64540c7937baa0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/308f70fd66b0d274475e4b4d1671802d7e2dc28e15bd220b8049276942804ec21e27beb91843038d34c73b04b9245eb488e311f925b802fbcfc4a269799c1812", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/03a42f1c534c01e3b7e4d0c02c4264ce7fed0c807d8b16ebfc1b1c1ef541a721b6e306c7f86aa233bc1e3e74dff69d0d3cae18797f27c07fcf8a476972efc1a9"} 11:03:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:03:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:03:03 INFO - Contents: 11:03:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed0ca3e2d3d7b6d0c4f0d74989ca8af3eb3ff1892fa7b1d1eaa6752a15696f43791c0da9a08f9e58baaed1cbf6c2b3b15ba3889126f9d8d9b64540c7937baa0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/308f70fd66b0d274475e4b4d1671802d7e2dc28e15bd220b8049276942804ec21e27beb91843038d34c73b04b9245eb488e311f925b802fbcfc4a269799c1812", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/03a42f1c534c01e3b7e4d0c02c4264ce7fed0c807d8b16ebfc1b1c1ef541a721b6e306c7f86aa233bc1e3e74dff69d0d3cae18797f27c07fcf8a476972efc1a9"} 11:03:03 INFO - Running post-run listener: copy_logs_to_upload_dir 11:03:03 INFO - Copying logs to upload dir... 11:03:03 INFO - mkdir: /builds/slave/test/build/upload/logs 11:03:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.552748 ========= master_lag: 0.52 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-14 11:03:04.279362) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 11:03:04.286670) ========= 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.ItGMS7IYsL/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.G7st70XK7x/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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/ed0ca3e2d3d7b6d0c4f0d74989ca8af3eb3ff1892fa7b1d1eaa6752a15696f43791c0da9a08f9e58baaed1cbf6c2b3b15ba3889126f9d8d9b64540c7937baa0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/308f70fd66b0d274475e4b4d1671802d7e2dc28e15bd220b8049276942804ec21e27beb91843038d34c73b04b9245eb488e311f925b802fbcfc4a269799c1812", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/03a42f1c534c01e3b7e4d0c02c4264ce7fed0c807d8b16ebfc1b1c1ef541a721b6e306c7f86aa233bc1e3e74dff69d0d3cae18797f27c07fcf8a476972efc1a9"} build_url:https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010967 build_url: 'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed0ca3e2d3d7b6d0c4f0d74989ca8af3eb3ff1892fa7b1d1eaa6752a15696f43791c0da9a08f9e58baaed1cbf6c2b3b15ba3889126f9d8d9b64540c7937baa0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/308f70fd66b0d274475e4b4d1671802d7e2dc28e15bd220b8049276942804ec21e27beb91843038d34c73b04b9245eb488e311f925b802fbcfc4a269799c1812", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/03a42f1c534c01e3b7e4d0c02c4264ce7fed0c807d8b16ebfc1b1c1ef541a721b6e306c7f86aa233bc1e3e74dff69d0d3cae18797f27c07fcf8a476972efc1a9"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 11:03:04.325256) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:03:04.325602) ========= 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.ItGMS7IYsL/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.G7st70XK7x/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.005221 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:03:04.402252) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 11:03:04.402587) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 11:03:04.403453) ========= ========= Total master_lag: 0.69 =========