builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0013 starttime: 1481911790.54 results: success (0) buildid: 20161216083049 builduid: 73c4a9f2bd6a4fbcbae1463c1cda9dc8 revision: 75b7b263b98c350a33810febb078f7370c8623b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 10:09:50.544185) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 10:09:50.544631) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 10:09:50.544921) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 10:09:50.569965) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 10:09:50.570256) ========= 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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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-12-16 10:09:50-- 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% 131M=0s 2016-12-16 10:09:50 (131 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090882 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 10:09:50.686474) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 10:09:50.687203) ========= 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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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.019886 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 10:09:50.737624) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 10:09:50.737979) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 75b7b263b98c350a33810febb078f7370c8623b0 --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 75b7b263b98c350a33810febb078f7370c8623b0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eTTZVsgyrN/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.GoCe6yuaEC/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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-12-16 10:09:50,810 truncating revision to first 12 chars 2016-12-16 10:09:50,811 Setting DEBUG logging. 2016-12-16 10:09:50,811 attempt 1/10 2016-12-16 10:09:50,811 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/75b7b263b98c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-16 10:09:51,450 unpacking tar archive at: mozilla-aurora-75b7b263b98c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.913122 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 10:09:51.674677) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 10:09:51.674991) ========= script_repo_revision: 75b7b263b98c350a33810febb078f7370c8623b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 10:09:51.675365) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 10:09:51.675629) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 10:09:51.695228) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-16 10:09:51.695508) ========= /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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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:09:51 INFO - MultiFileLogger online at 20161216 10:09:51 in /builds/slave/test 10:09:51 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:09:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:09:51 INFO - {'append_to_log': False, 10:09:51 INFO - 'base_work_dir': '/builds/slave/test', 10:09:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:09:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:09:51 INFO - 'branch': 'Mozilla-Aurora', 10:09:51 INFO - 'buildbot_json_path': 'buildprops.json', 10:09:51 INFO - 'config_files': ('talos/mac_config.py',), 10:09:51 INFO - 'default_actions': ('clobber', 10:09:51 INFO - 'read-buildbot-config', 10:09:51 INFO - 'download-and-extract', 10:09:51 INFO - 'populate-webroot', 10:09:51 INFO - 'create-virtualenv', 10:09:51 INFO - 'install', 10:09:51 INFO - 'run-tests'), 10:09:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:09:51 INFO - 'download_minidump_stackwalk': True, 10:09:51 INFO - 'download_symbols': 'ondemand', 10:09:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:09:51 INFO - 'tooltool.py': '/tools/tooltool.py', 10:09:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:09:51 INFO - '/tools/misc-python/virtualenv.py')}, 10:09:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:09:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:09:51 INFO - 'installer_path': 'installer.exe', 10:09:51 INFO - 'log_level': 'info', 10:09:51 INFO - 'log_name': 'talos', 10:09:51 INFO - 'log_to_console': True, 10:09:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:09:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:09:51 INFO - 'opt_config_files': (), 10:09:51 INFO - 'pip_index': False, 10:09:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:09:51 INFO - 'cmd': ('bash', 10:09:51 INFO - '-c', 10:09:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:09:51 INFO - 'enabled': True, 10:09:51 INFO - 'halt_on_failure': False, 10:09:51 INFO - 'name': 'check_screen_resolution'},), 10:09:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:09:51 INFO - 'cmd': ('bash', 10:09:51 INFO - '-c', 10:09:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:09:51 INFO - 'enabled': True, 10:09:51 INFO - 'halt_on_failure': False, 10:09:51 INFO - 'name': 'check_screen_resolution'},), 10:09:51 INFO - 'run_cmd_checks_enabled': True, 10:09:51 INFO - 'sps_profile': False, 10:09:51 INFO - 'sps_profile_interval': 0, 10:09:51 INFO - 'suite': 'dromaeojs', 10:09:51 INFO - 'system_bits': '32', 10:09:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:09:51 INFO - 'title': 't-yosemite-r7-0013', 10:09:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:09:51 INFO - 'use_talos_json': True, 10:09:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:09:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:09:51 INFO - 'work_dir': 'build'} 10:09:51 INFO - [mozharness: 2016-12-16 18:09:51.893112Z] Running clobber step. 10:09:51 INFO - Running pre-action listener: _resource_record_pre_action 10:09:51 INFO - Running main action method: clobber 10:09:51 INFO - rmtree: /builds/slave/test/build 10:09:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:09:53 INFO - Running post-action listener: _resource_record_post_action 10:09:53 INFO - [mozharness: 2016-12-16 18:09:53.415406Z] Finished clobber step (success) 10:09:53 INFO - [mozharness: 2016-12-16 18:09:53.415563Z] Running read-buildbot-config step. 10:09:53 INFO - Running pre-action listener: _resource_record_pre_action 10:09:53 INFO - Running main action method: read_buildbot_config 10:09:53 INFO - Using buildbot properties: 10:09:53 INFO - { 10:09:53 INFO - "project": "", 10:09:53 INFO - "product": "firefox", 10:09:53 INFO - "script_repo_revision": "production", 10:09:53 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:09:53 INFO - "repository": "", 10:09:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:09:53 INFO - "buildid": "20161216083049", 10:09:53 INFO - "pgo_build": "False", 10:09:53 INFO - "basedir": "/builds/slave/test", 10:09:53 INFO - "buildnumber": 18, 10:09:53 INFO - "slavename": "t-yosemite-r7-0013", 10:09:53 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:09:53 INFO - "platform": "yosemite_r7", 10:09:53 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:09:53 INFO - "branch": "mozilla-aurora", 10:09:53 INFO - "repo_path": "releases/mozilla-aurora", 10:09:53 INFO - "revision": "75b7b263b98c350a33810febb078f7370c8623b0", 10:09:53 INFO - "stage_platform": "macosx64", 10:09:53 INFO - "builduid": "73c4a9f2bd6a4fbcbae1463c1cda9dc8", 10:09:53 INFO - "slavebuilddir": "test" 10:09:53 INFO - } 10:09:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 10:09:53 INFO - Running post-action listener: _resource_record_post_action 10:09:53 INFO - [mozharness: 2016-12-16 18:09:53.418897Z] Finished read-buildbot-config step (success) 10:09:53 INFO - [mozharness: 2016-12-16 18:09:53.419038Z] Running download-and-extract step. 10:09:53 INFO - Running pre-action listener: _resource_record_pre_action 10:09:53 INFO - Running main action method: download_and_extract 10:09:53 INFO - mkdir: /builds/slave/test/build/tests 10:09:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:09:53 INFO - trying https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 10:09:53 INFO - Downloading https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/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:09:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/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:09:54 INFO - Downloaded 1199 bytes. 10:09:54 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:09:54 INFO - Using the following test package requirements: 10:09:54 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:09:54 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:09:54 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 10:09:54 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:09:54 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 10:09:54 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:09:54 INFO - u'jsshell-mac.zip'], 10:09:54 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:09:54 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 10:09:54 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:09:54 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:09:54 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 10:09:54 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:09:54 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 10:09:54 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:09:54 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 10:09:54 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:09:54 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 10:09:54 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:09:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:09:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:09:54 INFO - Fetch https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:09:55 INFO - Http code: 200 10:09:55 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 10:09:55 INFO - x-amz-cf-id: 0cXNn_wmsXhOuOM_sXiw0LuFb9MeoJcdSJX0rbdi2aEqfZITQo61gg== 10:09:55 INFO - x-amz-version-id: ntRlaePht61pywu5uVAF4pBp32Y2FKe8 10:09:55 INFO - Expected file size: 16280424 10:09:55 INFO - Obtained file size: 16280424 10:10:02 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:10:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:10:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:10:02 INFO - Fetch https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:10:02 INFO - Http code: 200 10:10:02 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 10:10:02 INFO - x-amz-cf-id: 8zIeN7W0zJyjek6xboZNGBtiNQPss5ONf7EyYwtkDfHSrfuyvuQFpw== 10:10:02 INFO - x-amz-version-id: ntRlaePht61pywu5uVAF4pBp32Y2FKe8 10:10:03 INFO - Expected file size: 16280424 10:10:03 INFO - Obtained file size: 16280424 10:10:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 10:10:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:10:09 INFO - Fetch https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 10:10:09 INFO - Http code: 200 10:10:09 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 10:10:09 INFO - x-amz-cf-id: UPRa8KpX5LUqZQEL4dpltn0jZ7nuNzKwWjPmNLvs3c5HcqWgpDgGCQ== 10:10:09 INFO - x-amz-version-id: Gu.483T6ht3CH_NDLMAT.DiucXeQ2HD9 10:10:10 INFO - Expected file size: 14409038 10:10:10 INFO - Obtained file size: 14409038 10:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:10:10 INFO - trying https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:10:10 INFO - Downloading https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 10:10:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/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:10:13 INFO - Downloaded 109726127 bytes. 10:10:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:10:13 INFO - mkdir: /builds/slave/test/properties 10:10:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:10:13 INFO - Writing to file /builds/slave/test/properties/build_url 10:10:13 INFO - Contents: 10:10:13 INFO - build_url:https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:10:13 INFO - Running post-action listener: _resource_record_post_action 10:10:13 INFO - Running post-action listener: set_extra_try_arguments 10:10:13 INFO - [mozharness: 2016-12-16 18:10:13.974535Z] Finished download-and-extract step (success) 10:10:13 INFO - [mozharness: 2016-12-16 18:10:13.974675Z] Running populate-webroot step. 10:10:13 INFO - Running pre-action listener: _resource_record_pre_action 10:10:13 INFO - Running main action method: populate_webroot 10:10:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:10:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:10:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:10:13 INFO - '--tppagecycles', 10:10:13 INFO - '7'], 10:10:13 INFO - 'tests': ['tsvgm']}}, 10:10:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:10:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:10:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:10:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:10:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:10:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:10:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:13 INFO - 'talos_options': ['--disable-e10s'], 10:10:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:10:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:10:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:13 INFO - 'talos_options': ['--disable-e10s'], 10:10:13 INFO - 'tests': ['damp', 'tps']}, 10:10:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:13 INFO - 'tests': ['damp', 'tps']}, 10:10:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:10:13 INFO - 'tests': ['dromaeo_dom']}, 10:10:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:10:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:10:13 INFO - 'tests': ['basic_compositor_video']}, 10:10:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:10:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:10:13 INFO - 'tests': ['a11yr', 10:10:13 INFO - 'ts_paint', 10:10:13 INFO - 'tpaint', 10:10:13 INFO - 'sessionrestore', 10:10:13 INFO - 'sessionrestore_no_auto_restore', 10:10:13 INFO - 'tabpaint']}, 10:10:13 INFO - 'other-e10s': {'tests': ['a11yr', 10:10:13 INFO - 'ts_paint', 10:10:13 INFO - 'tpaint', 10:10:13 INFO - 'sessionrestore', 10:10:13 INFO - 'sessionrestore_no_auto_restore', 10:10:13 INFO - 'tabpaint']}, 10:10:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:10:13 INFO - 'ts_paint', 10:10:13 INFO - 'tpaint', 10:10:13 INFO - 'sessionrestore', 10:10:13 INFO - 'sessionrestore_no_auto_restore', 10:10:13 INFO - 'tabpaint']}, 10:10:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:10:13 INFO - 'ts_paint', 10:10:13 INFO - 'tpaint', 10:10:13 INFO - 'sessionrestore', 10:10:13 INFO - 'sessionrestore_no_auto_restore', 10:10:13 INFO - 'tabpaint']}, 10:10:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:10:13 INFO - 'tests': ['a11yr', 10:10:13 INFO - 'ts_paint', 10:10:13 INFO - 'tpaint', 10:10:13 INFO - 'sessionrestore', 10:10:13 INFO - 'sessionrestore_no_auto_restore', 10:10:13 INFO - 'tabpaint']}, 10:10:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:10:13 INFO - 'tests': ['a11yr', 10:10:13 INFO - 'ts_paint', 10:10:13 INFO - 'tpaint', 10:10:13 INFO - 'sessionrestore', 10:10:13 INFO - 'sessionrestore_no_auto_restore', 10:10:13 INFO - 'tabpaint']}, 10:10:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:10:13 INFO - 'tests': ['tsvgx', 10:10:13 INFO - 'tsvgr_opacity', 10:10:13 INFO - 'tart', 10:10:13 INFO - 'tscrollx', 10:10:13 INFO - 'cart']}, 10:10:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:10:13 INFO - 'tsvgr_opacity', 10:10:13 INFO - 'tart', 10:10:13 INFO - 'tscrollx', 10:10:13 INFO - 'cart']}, 10:10:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:13 INFO - 'talos_options': ['--disable-e10s'], 10:10:13 INFO - 'tests': ['tp5o']}, 10:10:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:13 INFO - 'tests': ['tp5o']}, 10:10:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:13 INFO - 'talos_options': ['--disable-e10s', 10:10:13 INFO - '--xperf_path', 10:10:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:10:13 INFO - 'tests': ['tp5n']}, 10:10:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:13 INFO - 'talos_options': ['--xperf_path', 10:10:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:10:13 INFO - 'tests': ['tp5n']}}, 10:10:13 INFO - 'talos.zip': {'path': '', 10:10:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:10:13 INFO - Running post-action listener: _resource_record_post_action 10:10:13 INFO - [mozharness: 2016-12-16 18:10:13.993265Z] Finished populate-webroot step (success) 10:10:13 INFO - [mozharness: 2016-12-16 18:10:13.993355Z] Running create-virtualenv step. 10:10:13 INFO - Running pre-action listener: _resource_record_pre_action 10:10:13 INFO - Running main action method: create_virtualenv 10:10:13 INFO - Creating virtualenv /builds/slave/test/build/venv 10:10:13 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:10:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:10:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:10:14 INFO - Using real prefix '/tools/python27' 10:10:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:10:14 INFO - Installing distribute.............................................................................................................................................................................................done. 10:10:17 INFO - Installing pip.................done. 10:10:17 INFO - Return code: 0 10:10:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:10:17 INFO - Reading from file tmpfile_stdout 10:10:17 INFO - Output received: 10:10:17 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:10:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:10: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:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10: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:10:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:17 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 0x10b67a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13d53790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48ed30>, '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 0x10b2f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b486b20>, '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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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:10:17 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:10:17 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:10:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eTTZVsgyrN/Render', 10:10:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:17 INFO - 'HOME': '/Users/cltbld', 10:10:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:17 INFO - 'LOGNAME': 'cltbld', 10:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:17 INFO - 'MOZ_NO_REMOTE': '1', 10:10:17 INFO - 'NO_EM_RESTART': '1', 10:10:17 INFO - 'PAGER': '/bin/cat', 10:10:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:17 INFO - 'PWD': '/builds/slave/test', 10:10:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:17 INFO - 'SHELL': '/bin/bash', 10:10:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GoCe6yuaEC/Listeners', 10:10:17 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 10:10:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:17 INFO - 'USER': 'cltbld', 10:10:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:17 INFO - 'XPC_FLAGS': '0x0', 10:10:17 INFO - 'XPC_SERVICE_NAME': '0', 10:10:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:18 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:10:18 INFO - Cleaning up... 10:10:18 INFO - Return code: 0 10:10:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:10:18 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:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:18 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:10:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:18 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 0x10b67a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13d53790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48ed30>, '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 0x10b2f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b486b20>, '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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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:10:18 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:10:18 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:10:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eTTZVsgyrN/Render', 10:10:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:18 INFO - 'HOME': '/Users/cltbld', 10:10:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:18 INFO - 'LOGNAME': 'cltbld', 10:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:18 INFO - 'MOZ_NO_REMOTE': '1', 10:10:18 INFO - 'NO_EM_RESTART': '1', 10:10:18 INFO - 'PAGER': '/bin/cat', 10:10:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:18 INFO - 'PWD': '/builds/slave/test', 10:10:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:18 INFO - 'SHELL': '/bin/bash', 10:10:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GoCe6yuaEC/Listeners', 10:10:18 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 10:10:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:18 INFO - 'USER': 'cltbld', 10:10:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:18 INFO - 'XPC_FLAGS': '0x0', 10:10:18 INFO - 'XPC_SERVICE_NAME': '0', 10:10:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:18 INFO - Downloading/unpacking psutil>=3.1.1 10:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:10:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:10:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:10:21 INFO - Installing collected packages: psutil 10:10:21 INFO - Running setup.py install for psutil 10:10:22 INFO - building 'psutil._psutil_osx' extension 10:10:22 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:10:22 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:10:22 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:10:22 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:10:22 INFO - building 'psutil._psutil_posix' extension 10:10:22 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:10:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:10:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:10:22 INFO - ^ 10:10:22 INFO - 1 warning generated. 10:10:22 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:10:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:10:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:10:22 INFO - Successfully installed psutil 10:10:22 INFO - Cleaning up... 10:10:22 INFO - Return code: 0 10:10:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:10:22 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:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:22 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:10:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:22 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 0x10b67a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13d53790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48ed30>, '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 0x10b2f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b486b20>, '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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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:10:22 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:10:22 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:10:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eTTZVsgyrN/Render', 10:10:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:22 INFO - 'HOME': '/Users/cltbld', 10:10:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:22 INFO - 'LOGNAME': 'cltbld', 10:10:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:22 INFO - 'MOZ_NO_REMOTE': '1', 10:10:22 INFO - 'NO_EM_RESTART': '1', 10:10:22 INFO - 'PAGER': '/bin/cat', 10:10:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:22 INFO - 'PWD': '/builds/slave/test', 10:10:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:22 INFO - 'SHELL': '/bin/bash', 10:10:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GoCe6yuaEC/Listeners', 10:10:22 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 10:10:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:22 INFO - 'USER': 'cltbld', 10:10:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:22 INFO - 'XPC_FLAGS': '0x0', 10:10:22 INFO - 'XPC_SERVICE_NAME': '0', 10:10:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:10:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:10:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:10:25 INFO - Installing collected packages: mozsystemmonitor 10:10:25 INFO - Running setup.py install for mozsystemmonitor 10:10:25 INFO - Successfully installed mozsystemmonitor 10:10:25 INFO - Cleaning up... 10:10:25 INFO - Return code: 0 10:10:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:10:26 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:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:26 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:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:26 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 0x10b67a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13d53790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48ed30>, '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 0x10b2f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b486b20>, '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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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:10:26 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:10:26 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:10:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eTTZVsgyrN/Render', 10:10:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:26 INFO - 'HOME': '/Users/cltbld', 10:10:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:26 INFO - 'LOGNAME': 'cltbld', 10:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:26 INFO - 'MOZ_NO_REMOTE': '1', 10:10:26 INFO - 'NO_EM_RESTART': '1', 10:10:26 INFO - 'PAGER': '/bin/cat', 10:10:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:26 INFO - 'PWD': '/builds/slave/test', 10:10:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:26 INFO - 'SHELL': '/bin/bash', 10:10:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GoCe6yuaEC/Listeners', 10:10:26 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 10:10:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:26 INFO - 'USER': 'cltbld', 10:10:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:26 INFO - 'XPC_FLAGS': '0x0', 10:10:26 INFO - 'XPC_SERVICE_NAME': '0', 10:10:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:26 INFO - Downloading/unpacking jsonschema==2.5.1 10:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:10:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:10: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:10: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:10: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:10: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:10: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:10: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:10:29 INFO - Downloading functools32-3.2.3-2.tar.gz 10:10:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:10:29 INFO - warning: no files found matching '*.txt' 10:10:29 INFO - no previously-included directories found matching 'build' 10:10:29 INFO - no previously-included directories found matching 'dist' 10:10:29 INFO - no previously-included directories found matching '.git*' 10:10:29 INFO - Installing collected packages: jsonschema, functools32 10:10:29 INFO - Running setup.py install for functools32 10:10:29 INFO - warning: no files found matching '*.txt' 10:10:29 INFO - no previously-included directories found matching 'build' 10:10:29 INFO - no previously-included directories found matching 'dist' 10:10:29 INFO - no previously-included directories found matching '.git*' 10:10:29 INFO - Successfully installed jsonschema functools32 10:10:29 INFO - Cleaning up... 10:10:29 INFO - Return code: 0 10:10:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:10:29 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:10:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:29 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:10:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:29 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 0x10b67a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13d53790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48ed30>, '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 0x10b2f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b486b20>, '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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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:10:29 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:10:29 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:10:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eTTZVsgyrN/Render', 10:10:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:29 INFO - 'HOME': '/Users/cltbld', 10:10:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:29 INFO - 'LOGNAME': 'cltbld', 10:10:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:29 INFO - 'MOZ_NO_REMOTE': '1', 10:10:29 INFO - 'NO_EM_RESTART': '1', 10:10:29 INFO - 'PAGER': '/bin/cat', 10:10:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:29 INFO - 'PWD': '/builds/slave/test', 10:10:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:29 INFO - 'SHELL': '/bin/bash', 10:10:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GoCe6yuaEC/Listeners', 10:10:29 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 10:10:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:29 INFO - 'USER': 'cltbld', 10:10:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:29 INFO - 'XPC_FLAGS': '0x0', 10:10:29 INFO - 'XPC_SERVICE_NAME': '0', 10:10:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:10:29 INFO - Cleaning up... 10:10:29 INFO - Return code: 0 10:10:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:10:29 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:10:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:29 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:10:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:29 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 0x10b67a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13d53790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48ed30>, '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 0x10b2f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b486b20>, '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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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:10:29 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:10:29 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:10:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eTTZVsgyrN/Render', 10:10:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:29 INFO - 'HOME': '/Users/cltbld', 10:10:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:29 INFO - 'LOGNAME': 'cltbld', 10:10:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:29 INFO - 'MOZ_NO_REMOTE': '1', 10:10:29 INFO - 'NO_EM_RESTART': '1', 10:10:29 INFO - 'PAGER': '/bin/cat', 10:10:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:29 INFO - 'PWD': '/builds/slave/test', 10:10:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:29 INFO - 'SHELL': '/bin/bash', 10:10:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GoCe6yuaEC/Listeners', 10:10:29 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 10:10:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:29 INFO - 'USER': 'cltbld', 10:10:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:29 INFO - 'XPC_FLAGS': '0x0', 10:10:29 INFO - 'XPC_SERVICE_NAME': '0', 10:10:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:30 INFO - Downloading/unpacking blobuploader==1.2.4 10:10:30 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:10:30 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:10:30 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:10:30 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:10:30 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:10:30 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:10:32 INFO - Downloading blobuploader-1.2.4.tar.gz 10:10:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:10:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:10:32 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:10:32 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:10:32 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:10:32 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:10:32 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:10:32 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:10:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:10:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:10:33 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:10:33 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:10:33 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:10:33 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:10:33 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:10:33 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:10:34 INFO - Downloading docopt-0.6.1.tar.gz 10:10:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:10:34 INFO - Installing collected packages: blobuploader, requests, docopt 10:10:34 INFO - Running setup.py install for blobuploader 10:10:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:10:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:10:34 INFO - Running setup.py install for requests 10:10:34 INFO - Running setup.py install for docopt 10:10:34 INFO - Successfully installed blobuploader requests docopt 10:10:34 INFO - Cleaning up... 10:10:35 INFO - Return code: 0 10:10:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:35 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 0x10b67a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13d53790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48ed30>, '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 0x10b2f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b486b20>, '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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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:10:35 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:10:35 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:10:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eTTZVsgyrN/Render', 10:10:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:35 INFO - 'HOME': '/Users/cltbld', 10:10:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:35 INFO - 'LOGNAME': 'cltbld', 10:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:35 INFO - 'MOZ_NO_REMOTE': '1', 10:10:35 INFO - 'NO_EM_RESTART': '1', 10:10:35 INFO - 'PAGER': '/bin/cat', 10:10:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:35 INFO - 'PWD': '/builds/slave/test', 10:10:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:35 INFO - 'SHELL': '/bin/bash', 10:10:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GoCe6yuaEC/Listeners', 10:10:35 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 10:10:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:35 INFO - 'USER': 'cltbld', 10:10:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:35 INFO - 'XPC_FLAGS': '0x0', 10:10:35 INFO - 'XPC_SERVICE_NAME': '0', 10:10:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:10:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-QZDNVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:10:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-_WI5_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:10:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-a3aeSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:10:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-mepbRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:10:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-D29bik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:10:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-e7L2Qo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:10:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Du17mO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:10:36 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-XHthsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:10:36 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-gz0jT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:10:36 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-ACzYWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:10:36 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Fk1nNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:10:36 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-i722YR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:10:36 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-aLBWcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:10:36 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-QWOuHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:10:36 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-CRHf0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:10:36 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-mr2Rry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:10:37 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-ASDkPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:10:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:10:37 INFO - Running setup.py install for manifestparser 10:10:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:10:37 INFO - Running setup.py install for mozcrash 10:10:37 INFO - Running setup.py install for mozdebug 10:10:37 INFO - Running setup.py install for mozdevice 10:10:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:10:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:10:37 INFO - Running setup.py install for mozfile 10:10:37 INFO - Running setup.py install for mozhttpd 10:10:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:10:37 INFO - Running setup.py install for mozinfo 10:10:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:10:38 INFO - Running setup.py install for mozInstall 10:10:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:10:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:10:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:10:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:10:38 INFO - Running setup.py install for mozleak 10:10:38 INFO - Running setup.py install for mozlog 10:10:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:10:38 INFO - Running setup.py install for moznetwork 10:10:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:10:38 INFO - Running setup.py install for mozprocess 10:10:38 INFO - Running setup.py install for mozprofile 10:10:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:10:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:10:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:10:39 INFO - Running setup.py install for mozrunner 10:10:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:10:39 INFO - Running setup.py install for mozscreenshot 10:10:39 INFO - Running setup.py install for moztest 10:10:39 INFO - Running setup.py install for mozversion 10:10:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:10:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:10:39 INFO - Cleaning up... 10:10:39 INFO - Return code: 0 10:10:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:10:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:10:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:39 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 0x10b67a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13d53790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48ed30>, '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 0x10b2f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b486b20>, '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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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:10:39 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:10:39 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:10:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eTTZVsgyrN/Render', 10:10:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:39 INFO - 'HOME': '/Users/cltbld', 10:10:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:39 INFO - 'LOGNAME': 'cltbld', 10:10:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:39 INFO - 'MOZ_NO_REMOTE': '1', 10:10:39 INFO - 'NO_EM_RESTART': '1', 10:10:39 INFO - 'PAGER': '/bin/cat', 10:10:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:39 INFO - 'PWD': '/builds/slave/test', 10:10:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:39 INFO - 'SHELL': '/bin/bash', 10:10:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GoCe6yuaEC/Listeners', 10:10:39 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 10:10:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:39 INFO - 'USER': 'cltbld', 10:10:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:39 INFO - 'XPC_FLAGS': '0x0', 10:10:39 INFO - 'XPC_SERVICE_NAME': '0', 10:10:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:10:39 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-dCoPb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:10:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-HgrE18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:10:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-L2gSDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:10:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-5OflJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:10:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-YGARcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:10:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-2hbEvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:10:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-c3ulPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:10:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-I45IlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:10:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-cARsnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:10:40 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-m5ZLOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:10:41 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:10:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:10:41 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Oa2RjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:10:41 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:10:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:10:41 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-zVPEde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:10:41 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:10:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:10:41 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-0WBfug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:10:41 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:10:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:10:41 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-kj2okq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:10:41 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:10:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:10:41 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-2jl0Ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:10:41 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:10:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:10:41 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-nNocr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:10:41 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:10:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:10:41 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-68Ggrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:10:41 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:10:41 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:10:41 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:10:41 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:10:41 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:10:41 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:10:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:10:41 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:10:41 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:10:41 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:10:41 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:10:41 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:10:41 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:10:44 INFO - Downloading blessings-1.6.tar.gz 10:10:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:10:44 INFO - Installing collected packages: blessings 10:10:44 INFO - Running setup.py install for blessings 10:10:44 INFO - Successfully installed blessings 10:10:44 INFO - Cleaning up... 10:10:44 INFO - Return code: 0 10:10:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:10:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:10:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:10:44 INFO - Reading from file tmpfile_stdout 10:10:44 INFO - Current package versions: 10:10:44 INFO - blessings == 1.6 10:10:44 INFO - blobuploader == 1.2.4 10:10:44 INFO - docopt == 0.6.1 10:10:44 INFO - functools32 == 3.2.3-2 10:10:44 INFO - jsonschema == 2.5.1 10:10:44 INFO - manifestparser == 1.1 10:10:44 INFO - mozInstall == 1.12 10:10:44 INFO - mozcrash == 1.0 10:10:44 INFO - mozdebug == 0.1 10:10:44 INFO - mozdevice == 0.48 10:10:44 INFO - mozfile == 1.2 10:10:44 INFO - mozhttpd == 0.7 10:10:44 INFO - mozinfo == 0.9 10:10:44 INFO - mozleak == 0.1 10:10:44 INFO - mozlog == 3.4 10:10:44 INFO - moznetwork == 0.27 10:10:44 INFO - mozprocess == 0.23 10:10:44 INFO - mozprofile == 0.28 10:10:44 INFO - mozrunner == 6.13 10:10:44 INFO - mozscreenshot == 0.1 10:10:44 INFO - mozsystemmonitor == 0.3 10:10:44 INFO - moztest == 0.7 10:10:44 INFO - mozversion == 1.4 10:10:44 INFO - psutil == 3.1.1 10:10:44 INFO - requests == 1.2.3 10:10:44 INFO - wsgiref == 0.1.2 10:10:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:10: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:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10: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:10:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10: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 0x10b67a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13d53790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48ed30>, '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 0x10b2f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b486b20>, '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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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:10: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:10: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:10:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eTTZVsgyrN/Render', 10:10:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:44 INFO - 'HOME': '/Users/cltbld', 10:10:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:44 INFO - 'LOGNAME': 'cltbld', 10:10:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:44 INFO - 'MOZ_NO_REMOTE': '1', 10:10:44 INFO - 'NO_EM_RESTART': '1', 10:10:44 INFO - 'PAGER': '/bin/cat', 10:10:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:44 INFO - 'PWD': '/builds/slave/test', 10:10:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:44 INFO - 'SHELL': '/bin/bash', 10:10:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GoCe6yuaEC/Listeners', 10:10:44 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 10:10:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:44 INFO - 'USER': 'cltbld', 10:10:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:44 INFO - 'XPC_FLAGS': '0x0', 10:10:44 INFO - 'XPC_SERVICE_NAME': '0', 10:10:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:45 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:10:45 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:10:45 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:10:45 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:10:45 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:10:45 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:10:45 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:10:45 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:10:45 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:10:45 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:10:45 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:10:45 INFO - Cleaning up... 10:10:45 INFO - Return code: 0 10:10:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:10:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:10:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:45 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 0x10b67a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f13d53790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b48ed30>, '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 0x10b2f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b486b20>, '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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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:10:45 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:10:45 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:10:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eTTZVsgyrN/Render', 10:10:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:45 INFO - 'HOME': '/Users/cltbld', 10:10:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:45 INFO - 'LOGNAME': 'cltbld', 10:10:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:45 INFO - 'MOZ_NO_REMOTE': '1', 10:10:45 INFO - 'NO_EM_RESTART': '1', 10:10:45 INFO - 'PAGER': '/bin/cat', 10:10:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:45 INFO - 'PWD': '/builds/slave/test', 10:10:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:45 INFO - 'SHELL': '/bin/bash', 10:10:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GoCe6yuaEC/Listeners', 10:10:45 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 10:10:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:45 INFO - 'USER': 'cltbld', 10:10:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:45 INFO - 'XPC_FLAGS': '0x0', 10:10:45 INFO - 'XPC_SERVICE_NAME': '0', 10:10:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:10:45 INFO - Cleaning up... 10:10:45 INFO - Return code: 0 10:10:45 INFO - Running post-action listener: _resource_record_post_action 10:10:45 INFO - Running post-action listener: _start_resource_monitoring 10:10:45 INFO - Starting resource monitoring. 10:10:45 INFO - [mozharness: 2016-12-16 18:10:45.483191Z] Finished create-virtualenv step (success) 10:10:45 INFO - [mozharness: 2016-12-16 18:10:45.484211Z] Running install step. 10:10:45 INFO - Running pre-action listener: _resource_record_pre_action 10:10:45 INFO - Running main action method: install 10:10:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:10:45 INFO - Reading from file tmpfile_stdout 10:10:45 INFO - Detecting whether we're running mozinstall >=1.0... 10:10:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:10:45 INFO - Reading from file tmpfile_stdout 10:10:45 INFO - Output received: 10:10:45 INFO - Usage: mozinstall [options] installer 10:10:45 INFO - Options: 10:10:45 INFO - -h, --help show this help message and exit 10:10:45 INFO - -d DEST, --destination=DEST 10:10:45 INFO - Directory to install application into. [default: 10:10:45 INFO - "/builds/slave/test"] 10:10:45 INFO - --app=APP Application being installed. [default: firefox] 10:10:45 INFO - mkdir: /builds/slave/test/build/application 10:10: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:10: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:11:17 INFO - Reading from file tmpfile_stdout 10:11:17 INFO - Output received: 10:11:17 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:11:17 INFO - Running post-action listener: _resource_record_post_action 10:11:17 INFO - [mozharness: 2016-12-16 18:11:17.063953Z] Finished install step (success) 10:11:17 INFO - [mozharness: 2016-12-16 18:11:17.064088Z] Running run-tests step. 10:11:17 INFO - Running pre-action listener: _resource_record_pre_action 10:11:17 INFO - Running main action method: run_tests 10:11:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:11:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:11:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:11:17 INFO - 2016-12-16 10:11:17.078 screenresolution[1901:14118] starting screenresolution argv=screenresolution get 10:11:17 INFO - 2016-12-16 10:11:17.081 screenresolution[1901:14118] Display 0: 1600x1200x32@60 10:11:17 INFO - 2016-12-16 10:11:17.086 screenresolution[1902:14121] starting screenresolution argv=screenresolution list 10:11:17 INFO - Available Modes on Display 0 10:11:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:11:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:11:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:11:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:11:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:11:17 INFO - Intel Iris: 10:11:17 INFO - Chipset Model: Intel Iris 10:11:17 INFO - Type: GPU 10:11:17 INFO - Bus: Built-In 10:11:17 INFO - VRAM (Dynamic, Max): 1536 MB 10:11:17 INFO - Vendor: Intel (0x8086) 10:11:17 INFO - Device ID: 0x0a2e 10:11:17 INFO - Revision ID: 0x0009 10:11:17 INFO - Displays: 10:11:17 INFO - Display: 10:11:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:11:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:11:17 INFO - Display Serial Number: 1600x1200 60 10:11:17 INFO - Main Display: Yes 10:11:17 INFO - Mirror: Off 10:11:17 INFO - Online: Yes 10:11:17 INFO - Rotation: Supported 10:11:17 INFO - Return code: 0 10:11:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:11:17 INFO - Python 2.7.3 10:11:17 INFO - Return code: 0 10:11:17 INFO - grabbing minidump binary from tooltool 10:11: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:11: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 0x10b48ed30>, '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 0x10b2f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b486b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:11: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:11: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:11: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:11:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:11:17 INFO - Return code: 0 10:11:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:11:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:11:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:11:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:11:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:11: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/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0013', '--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:11: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/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0013 --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:11:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eTTZVsgyrN/Render', 10:11:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:17 INFO - 'HOME': '/Users/cltbld', 10:11:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:17 INFO - 'LOGNAME': 'cltbld', 10:11:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:11:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:17 INFO - 'MOZ_NO_REMOTE': '1', 10:11:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:11:17 INFO - 'NO_EM_RESTART': '1', 10:11:17 INFO - 'PAGER': '/bin/cat', 10:11: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:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:17 INFO - 'PWD': '/builds/slave/test', 10:11:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:11:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:17 INFO - 'SHELL': '/bin/bash', 10:11:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GoCe6yuaEC/Listeners', 10:11:17 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 10:11:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:17 INFO - 'USER': 'cltbld', 10:11:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:17 INFO - 'XPC_FLAGS': '0x0', 10:11:17 INFO - 'XPC_SERVICE_NAME': '0', 10:11:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11: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/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0013', '--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:11:17 INFO - mozversion application_buildid: 20161216083049 10:11:17 INFO - mozversion application_changeset: 75b7b263b98c350a33810febb078f7370c8623b0 10:11:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:11:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:11:17 INFO - mozversion application_name: Firefox 10:11:17 INFO - mozversion application_remotingname: firefox-dev 10:11:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:11:17 INFO - mozversion application_vendor: Mozilla 10:11:17 INFO - mozversion application_version: 52.0a2 10:11:17 INFO - mozversion platform_buildid: 20161216083049 10:11:17 INFO - mozversion platform_changeset: 75b7b263b98c350a33810febb078f7370c8623b0 10:11:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:11:17 INFO - mozversion platform_version: 52.0a2 10:11:17 INFO - using testdate: 1481911877 10:11:17 INFO - actual date: 1481911877 10:11:17 INFO - starting webserver on 'localhost:51551' 10:11:17 INFO - SUITE-START | Running 2 tests 10:11:17 INFO - TEST-START | dromaeo_css 10:11:17 INFO - operating with platform_type : mac_ 10:11:17 INFO - Initialising browser for dromaeo_css test... 10:11:17 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpzMphl0/profile http://localhost:51551/getInfo.html) 10:11:20 INFO - PROCESS | 1912 | __metrics Screen width/height:1600/1200 10:11:20 INFO - PROCESS | 1912 | colorDepth:24 10:11:20 INFO - PROCESS | 1912 | Browser inner width/height: 1024/689 10:11:20 INFO - PROCESS | 1912 | __metrics 10:11:20 INFO - PROCESS | 1912 | 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:11:20 INFO - PROCESS | 1912 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:11:21 INFO - TEST-INFO | 1912: exit 0 10:11:21 INFO - Browser initialized. 10:11:21 INFO - Running cycle 1/1 for dromaeo_css test... 10:11:21 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpzMphl0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:11:33 INFO - PROCESS | 1914 | RSS: Main: 222371840 10:11:33 INFO - PROCESS | 1914 | 10:12:27 INFO - PROCESS | 1914 | 1481911947994 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:14:38 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51551/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51551/tests/dromaeo/cssquery-ext.html) 10:14:40 INFO - PROCESS | 1914 | RSS: Main: 333910016 10:14:40 INFO - PROCESS | 1914 | 10:17:43 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51551/tests/dromaeo/cssquery-ext.html (next: http://localhost:51551/tests/dromaeo/cssquery-jquery.html) 10:17:44 INFO - PROCESS | 1914 | RSS: Main: 369266688 10:17:44 INFO - PROCESS | 1914 | 10:20:46 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51551/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51551/tests/dromaeo/cssquery-mootools.html) 10:20:47 INFO - PROCESS | 1914 | RSS: Main: 326000640 10:20:47 INFO - PROCESS | 1914 | 10:23:49 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51551/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51551/tests/dromaeo/cssquery-prototype.html) 10:23:50 INFO - PROCESS | 1914 | RSS: Main: 315072512 10:23:50 INFO - PROCESS | 1914 | 10:26:53 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51551/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51551/tests/dromaeo/cssquery-yui.html) 10:26:54 INFO - PROCESS | 1914 | RSS: Main: 309637120 10:26:54 INFO - PROCESS | 1914 | 10:29:57 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51551/tests/dromaeo/cssquery-yui.html (next: http://localhost:51551/tests/dromaeo/cssquery-dojo.html) 10:29:57 INFO - PROCESS | 1914 | RSS: Main: 381689856 10:29:57 INFO - PROCESS | 1914 | 10:29:57 INFO - PROCESS | 1914 | __start_tp_report 10:29:57 INFO - PROCESS | 1914 | _x_x_mozilla_page_load 10:29:57 INFO - PROCESS | 1914 | _x_x_mozilla_page_load_details 10:29:57 INFO - PROCESS | 1914 | |i|pagename|runs| 10:29:57 INFO - PROCESS | 1914 | |0;dojo.html;3317.767756257062;3335.2662414268866;3346.079828047285;3363.604104296717;3402.353552824963;5658.009848276551;5762.855928601787;5767.106098554731;5786.363500015001;5794.507466865316;5680.147977803332;5769;5777.480026797587;5807.73865176067;5813.941860581393;5200.843974680756;5234.685918844882;5263.921041184385;5268.025415298163;5293.841184764463;4291.334843099309;5144.614153938451;5209.244659524366;5325.520703136717;5337.412884582693;5293.655912365684;5296.523312901838;5299.920501192485;5300.01949639318;5301.363836339645;4315.805788739514;5194.091034069042;5225.581953443715;5271.789128434858;5303.204519322094;5164.457731938168;5204.583633309364;5250.291210686585;5251.474852514756;5367.141257398836;5763.567732420085;5832.62087964281;5875.9118613220835;5890;5906.3798301178185;6096.817095487142;6123.526657168159;6125.907024958145;6126.731429653759;6185.010398336244;6115.027386288435;6194.070889366586;6224.9688751556405;6230.906536401958;6247.406496382836;5816.083577500352;5822.3304320003745;5842.152887831241;5897.144913987448;5898.439648233411;6125.215553761287;6138.401218689926;6192.442680158807;6207.81376558704;6258.593191442633;6140.548102625561;6144.924638188389;6172.012478003588;6172.537059720539;6183.536234782409;6225.688715564203;6226.25284965807;6230.034344676583;6237.407446292595;6240.7503699852405;6093.086037094383;6106.164791923452;6107.61364822091;6172;6215.533834962396;6175.814725558241;6216.129741836146;6232.501399887998;6245.906311405332;6250.593711408744;4916.631252656065;4928.310036594879;4936.3829521309835;4939;4972.975135124301;4375.059362237071;4402.559744025636;4444.088961762831;4458.019235768126;4466.531014243523;5646.011947909099;5673;5689.829305120853;5755.884882302331;5798.420157984167;5110.821121260738;6179.876402471926;6200.937990620127;6202.193714817045;6224.844378890473;5667.886642267132;5682.460166284196;5687.488126068591;5689.374168841424;5702.030654788696;5281.867953301121;5300.231466437345;5333.973330133324;5378;5388.084025715638;6148.47737942262;6258.217722784652;6275.121482992385;6280.560360774702;6328.810135695937;6303.6532990685955;6311.589746666452;6320.367963203643;6321.083442900662;6329.49364050884;6189.96905015472;6250.124982502362;6333.303336633059;6350.079238510482;6534.673266336759;6078.7264573093435;6120.510359171345;6122.877542449215;6152.046432802923;6217.906731398942;6184.876302474016;6198.659073750987;6223.408776166257;6239.344868788712;6242.875142497215;6098.752142885754;6125.142480052886;6125.264968203844;6125.510168486406;6154;6128.63228206309;6132.570720049732;6145.907811382744;6162.630242185483;6165.321814600301;5801.651900885791;5860.970695146668;5915.438033386993;6005.039193728982;6010.4891084258325;6077.453029227391;6151.046587778901;6156.045812899008;6159.876802463836;6174;5837.3870743571315;5945.137954996588;6025.578209525466;6027;6057.878842423215 10:29:57 INFO - PROCESS | 1914 | |1;ext.html;497.990040199196;510.6655140882722;511.31994447384983;517.8938317644878;530.989380212396;34851.477227841606;39005.80497097518;39027.60972390275;39046;39077.41383879244;39576;40148.59851401492;40149.59850401495;40204.59795402045;40237;9178.403403778766;9215.17063464288;9339.766505837375;9370.344075914689;9386.81226375472;9316.301277404187;9431.81136377272;9438.669646562372;9440.244780417584;9447.527623618791;9446.622135114587;9472.289578281621;9472.952635236814;9476.241900647932;9477.905220947805;9341.859872101924;9369.484678342722;9406.717798466058;9444.244460443148;9451.716448506488;9318.509038553799;9400.435973841591;9409.294302927308;9419.764505887339;9430.528473576362;38205;38495.807520962495;38567.614323856964;38573.61426385756;38681.80659096715;3143.2613335866054;3667.743257971943;3792.2119406262755;3840.347140986039;3849.903752406184;28353;29128.563071553945;29172.562411563842;29751.25621859449;29921.25196870074;3165.667604901475;3190.122716253026;3216.3245718399166;3223.629282632504;3230.8869189578254;380694;384804;487402;488183.3544993656;489545;32120.518192227137;47487.525124749;57324.853502929705;57381;57489.85020299571;33176.66823331784;34349;34572.65427345745;34628.826855866064;34691.65308346935;14248;14361.210133442552;14385.992980491264;14388.784168237486;14400.567982960529;12095.879041209651;12186.451609677586;12193.573224937085;12198.695032624255;12203;7687.192844751333;7692.8461430772195;7784.84430311402;7786.104597971193;7793.493422927491;2879.481693295185;3158.6051743532057;3226.548283240348;3236.3527294541186;3270.787398819069;890.8797312362877;909.5315912305168;1022.5756311130798;1050.2490719135856;1061.2200032975757;28781.712182878324;29539.556906646416;29541.966031188804;30071.548926766118;30399.848000760296;972.8714690958595;1016.588281745894;1065.3607835298758;1072.340510585984;1129.9887001130048;12969.481220751253;14498.565043048726;15192.012519186217;15397.84602153964;15839.920800395925;631017;667329;668088;668398;668514;56114.43885561174;57058;57076.71461642665;57083.71458142766;57181;1355.613650109714;1361.4213959067356;1386.0990356268305;1386.5770939863237;1399.0905911157624;28821.855890720413;29023.7097629021;29076.418471630026;29169;29988;49131;49446.50553494419;49483.25775113304;49528.257076144604;49623;564601;568062;568947;570670;570780;26051.218463446126;26253.868730656224;26474.735252648;26486;26501.469970600094;35967.64032359748;45789.08421831478;46028;46607.53392466032;47140.05719885651;37465.65007544562;37787.62212377841;38038.049048774;38283.2343353137;39166.02084947899;36982.26035479329;38025.80987095047;38105.61894381021;38292.80853595714;38967.805160974014;31772.205694857814;31804.05593848294;32318.676813231566;32383;37733.81133094317;27768.44463110686;34867.65132348644;34993.65006349904;35555;35556.82221588876;25077;25487.74512254854;25691.614625781003;25758.227253182435;25960.74039259583 10:29:57 INFO - PROCESS | 1914 | |2;jquery.html;344.314813521093;348.9371913055652;367.36629314432605;372.85458671118266;394.9842006319747;14835;15080.396784128636;15856.36574537017;20190;20653.38039858803;15430.996985195954;18028.63942721145;19918.800811991874;20606.89696551519;20661.27685531007;5637.492625663689;5952.9702351488395;5970.1940238067955;5977.252843394575;5980.983232850426;4770.689905156171;5860.794872179475;6472.158619379474;6547.77082802102;6599.175103112111;6230.190075290228;6527.543071984963;6530.346965303479;6537.869242615168;6554.901676474857;6432.646204458753;6475.19060117483;6483.059956306309;6520.4457621102265;6526.118973938505;5113.693178409308;5381.3811411687375;5458.8908221835345;5691.06097493913;5694.829155125353;12920.54778082772;13007.414666340037;13022;13179.60461186166;13196;2142.7428708554917;2199.3182113544854;2309.4226443389152;2323.9767602323927;2379.179183181817;9571.712848614552;10054.296199266055;10211.642592509226;10296.691099267035;13930.30348482582;1522.8020357353585;1746.5982823950435;1780.9670391173088;1833.504953662517;1843.5483306589838;70010.89967300989;113487.43256283748;113732.4313378436;115414.42292788567;116414.6717065654;11178.608748693756;11380.487878045507;11678;11743.354115523729;11895.702607434883;14671.899607529478;14683.19242441676;14709;14711.852881471048;14717.190554519604;8300.128486508951;8373.330133589297;8451.619677114544;8459.238668519863;8557.957210214034;7944.6027698614835;8262.17378262169;8396.622152003174;8437.114103019218;8437.704680336159;4424.579664931826;4491.30384790358;4524.88687782808;4571.702839315434;4587.151376995266;1835.6420498003029;1877.915493802782;1885.566319746466;1889.4142815726893;1891.2718603337814;371.6952099278566;384.6884023940617;397.5785667192785;809.5142914251402;927.174814415171;10982.780344393068;11392.601258955896;11491.54033838637;11736.119791015708;12381.133320667646;423.6780047164116;424.4058318354267;428.3403558519907;429.0282510114593;438.90563528841454;10994.526231597827;12002;12135.757284854253;12187.939060304641;12201.511939522497;542929;622511;623997;631357;638392;82659;100327.49836250771;106802;116298.41850790862;133971.9904201438;890.0743227043931;945.3052006774877;1004.5379125602159;1007.2848277722752;1008.5965613754411;15422.84577154259;18819.964901930525;19372.806271937665;19434.514137146685;19486;54414.727926360116;55008.449915500336;55768.721156393956;55958.601034974454;57450;76223;76299.8555021664;77998.61000695187;79940.60029699815;83809.58095209485;6769.830754231184;7256;7661.578613176329;8210.343172546302;8392.950881139857;12157.392130393622;12173.513059477522;12706.555270565486;13137.146085504412;13228.801567976296;9190.862137067816;10813.56745730162;11265.887341126483;11566.07471402269;11680.299182049104;9268.934072581469;10733.731656708645;11034.724131896766;11150.10799136051;11225.943870280596;11311.434428278388;11460.770784584429;11530.308181509135;11664.825027624762;12021.398930053287;7303.598302093435;7503.09962804467;7562.732573179509;7700.730474433368;7847.411444141826;7705.922940770521;8705.47767133974;8746.781330466534;10415.42715150674;10985.231033827555 10:29:57 INFO - PROCESS | 1914 | |3;mootools.html;1200.921940073895;1448.710647523704;1547.195458361652;1571.8192407873098;1748.8426041656248;8029.478083924548;8353.248207661309;8382.538960357186;8384.16158384162;8413.032501262342;7386.0767404074495;7642.808929776758;7972.202779722025;8208.09710931797;8229.300509456703;2264.807491363232;2276.692646492727;2277.36233854521;2287.8055365293885;2294.2314324701247;2342.976570234293;2373.560891235127;2375.1212051540956;2381.761823817622;2382.1424287256573;2317.385892738426;2324.140068174769;2345.94135146621;2351.9529609407887;2357.434215788207;2308.8152947764306;2314.8148148148107;2332.8950197240997;2333.8482998605036;2340.847844890077;2284.588774020676;2345.2495201535517;2349.5418393413293;2349.612313968193;2350.059976009593;8242.258196762261;8323.001239851195;8342.332613390914;8342.582870856433;8351.540665263408;4994.126027945095;5157.200633901752;5162.638615296931;5162.638615296931;5166.819161329336;3202.327511222646;3365.6297807241185;3452.085197422685;3535.9116022099397;3537.451694987281;1015.7105225010837;1059.8145324568247;1083.712816103733;1097.7310558913039;1103.9116870650328;10731;10810.567577296899;10846.620368287151;10847.294925829796;10850.945745271301;4428.2914733642465;4612.423447069116;4700.835470758576;4725.243960966229;4738.549837765407;9242.81514369709;9248.260139188984;9266.907330926739;9268.536573171314;9269.768255793659;5087.440381558025;5115.7442127893455;5121.052605268037;5122.206058061007;5127.2052831811125;3502.614712381636;4282.293421585404;4446.5331140230455;4550.704204226715;4710.29345598163;3731.533558305212;3759.022654109951;3820.9808950955066;3864.729468937204;3866.845326186977;1248.5505218121468;1259.3388471052588;1259.345140526939;1272.249372870011;1274.7577960187534;763.5495057915746;831.3515457942814;872.389327470773;891.2558014058334;909.3225546967551;5464.039781956604;5487.917681234785;5536.612437129363;5573.832785016456;5669.518090962232;424.8279446824041;428.400239664466;444.5643269595814;448.52008351064046;450.9549045095464;3686.557613086447;3703.166787472797;3705.073731567108;3768.698504119664;3798.1454172810895;9738.659146929824;9930.155936745314;10050.547725352231;10087.344322619007;10090.69727908164;9981.700548983543;10030.498475076218;10044.347117437343;10055.346402483816;10071.949640251752;7235.819104522429;7587.772366829004;7708.537487750753;7800.882986755203;7814.843703125905;5528.530074943514;5578.442155784552;5652.85867853307;5666.291713535808;5751.166080918159;9164.587593558439;9167.541622918961;9205.355625106178;9372.250219982521;9432.62269509212;9211.17099461025;9384.436933783994;9503.857442138511;9532.52337383142;9561.617535298788;6540.50946179029;6717.731290748511;6798.864022719419;7068.82327941785;7242.058532390755;7735.4198435118715;7943.682252709827;7952.721654742057;7955.84088318242;7956.085050219299;7022.754203602849;7094.929050709633;7145.356917877416;7167.8566428672175;7286.125664920243;6735.6295403753265;6784.830379240559;6976.7558135465015;7000.684969176501;7033.472489563201;6372.55392122547;6529.5102867284195;6625.900611490735;6637.236717777518;6709;4095.5904409558807;4767.642426818072;4773.284007398918;4815.398075240595;4820.758962051813;4711.2226482629985;4747.501512341154;4769.785359658755;4926.827561035347;4991.976644787737 10:29:57 INFO - PROCESS | 1914 | |4;prototype.html;278.2029485523973;297.64134218266986;308.3370752881305;309.69959139634545;312.05291938965246;5068.797248110071;5260.736963151846;5260.9210861837055;5278.234655974881;5313.57491400688;4114.567970363114;4287.571242875711;5097.59219262459;5150.69095854249;5216.843494695156;3353.3460975109742;3933.724639275252;3986.302397080514;4039.010442441606;4068.901396622925;3386.5089562013495;3975.9006024849323;4044.777537235451;4069.9186016279655;4078.6125318094732;3362.865485380582;3871.2838124946975;3975;4035.47538819953;4052.5947405259535;3512.4028915084496;3789.4694742736037;3842.846286148551;4001.659858911996;4021.5375231848275;3042.878284868603;3505.7922001368715;3509.2805974775365;3512.1921957949817;3517.402041652925;4126.8761937141935;4853.660243782937;5060.51925067118;5094.745262736848;5099.9490005100215;4568.675084225571;4615.9383341831235;4619.214733495314;4629.328747331651;4654.232051711463;5508.862278443031;5510.338759348904;5518.503334699896;5527.55779537636;5540;3870.3681496582312;3870.6709929656013;3881.573026967031;3889.358255887775;3911.2177564487215;4760.809567617292;5459.535939445152;5667.093265077568;5673.375928647845;5740.512056475163;3794.6015668354426;3894.805259737002;3918.529776426847;3934.744241624285;3942.8817135485983;3192.8403579820915;3335.832458639457;3344.130526063241;3588.69496092835;3674.0080178351695;4276.444062271947;4578.931316030262;4592.553345696162;4634;4667.346571479996;4371.344298355272;4573.77548828082;5496.285482887199;5497.32331638854;5556.860843527067;1143.4397145398862;1143.9942800285946;1161.7163034846542;1279.942402591867;1288.6327396691897;810.7703814510097;842.8819965204876;849.9830003399898;870.2298465857762;892.1836519584638;4408.184485870124;4884.7313397762755;4896.46138924718;4919.335889654851;4984.7878542631415;5406.621536492407;5443.863903402367;5461.863453413696;5466.5240385096595;5502.174673798882;4888.706677599355;4977.850664480072;5151.484851514893;5159.916417552281;5260.368755749335;2614.7908167346945;2678.7587019706375;2858.5992863496735;2878.4674835155565;2885.898993535216;4842.297866809293;5272.446393128743;5292.576593872481;5371.435999220103;5688.544916406678;5119.897602047939;5255.02781985334;5352.5450336721815;5456.099743542271;5490.917636235459;5082.161443361804;5091.261767043758;5652.658237965084;5658.132816170654;5683.238196159204;5357.151782634985;5407.378151512469;5425.782968681367;5428.45715428454;5713.771449141987;4841.8547443576745;5459.454054594509;5701.515371193329;5707.714614269187;5714.714264286852;4449.688521803572;4955.72743499111;5157.948420515747;5270.209468579744;5503.972480137573;3631.9636803631624;3733.637222413745;3824.9043773904764;3839.3665045267217;3883.7087218458164;5678.290213723285;5679.716014199356;5694.487496125369;5704.657720536781;5710.086386178192;5499.697516636624;5499.890002200013;5518.779248830162;5528.585356098389;5530.585206109477;5278.439956621112;5539.25220095282;5544.029794785977;5585.5531557476115;5616.297962754656;4547.999440123168;5158.638895277445;5475.315585551806;5478.8904221914545;5515.972420137874;5382.461753824587;5391.366022506569;5517.503424691797;5535.446455354432;5639.520640745583;4161.729487583298;4855.208255253173;5015.498450155102;5066.265391518283;5425.647332923348 10:29:57 INFO - PROCESS | 1914 | |5;yui.html;665.6505334699283;679.4292794052994;695.2352412346418;756.5498528375615;776.6854423958299;14073.788893166598;14539;14592;14692;15476.458323958665;14832.851671483282;14961;14996.250187490608;14998.775018374734;15014.399424023026;806.4677312973439;829.5769157729571;831.9916800831975;832.2967092806568;832.6544484039147;652.0903339840943;670.4368330602293;671.3253180553537;804.3926835239386;824.2458150792048;863.2619110660389;887.2458410351214;889.0664801957951;890.9064548222441;898.1018981018981;861.0356400831047;862.9913700862974;863.2360361080457;865.7489328094845;865.7619154732436;510.20917577754335;522.9111051121281;620.2340109964191;635.6218050260134;637.4645297949698;12395.876041239562;14069.788953165711;14341.924355673262;14604.926975365055;14612.561623151323;13350.065495415325;13601.659958501017;13675.658108547266;13786.241756703377;13789.310534473336;12163.51345946161;12434;12453.75092498145;12508.687282817911;12842.357882105858;4355.651547876162;4385.758783266919;4450.443694538183;4451.775761665535;4535.931961020588;92498;94061;95947.04052959451;103313;103464.48267758613;10440.42577658221;10693.78612427747;10791.514381852836;11033.779324413466;11314.717132071599;19592.314269000515;20770.961451927342;20800.375988720363;20943.8952805235;20948.89525552393;7870.803229919184;7895.447318687751;7955.880661790078;7976.361891048702;8201.384896132695;4143.668506519532;4212.241796476603;4304.332828411601;4420.292753159479;4515.616172625364;3353.9329213415594;3476.5480487536456;3484.9477257841154;3488.930221395558;3489.6335884732243;642.9260635026939;646.8027251688186;653.8169312592481;677.2313424263497;677.3734295776387;585.5842351930092;634.708034304216;651.1242379000237;652.54321974617;654.0385633119402;8695.260902823331;9077.319201059947;9080.727578172666;9106.590203440861;9107.22588579965;562.443181250559;583.9094940284263;602.4126476685284;612.3600837125215;642.2678146912524;3982.8406863725127;4228.5982831630945;4678;4702.905941881143;4784.210605250164;74532.62733686405;79737;84814.30084903914;110481;110870;76846.61576692192;87505.12494875098;90795;95461.0453895466;96168.55747163798;81858.95352616113;82793;83781.16218837733;85363;85933.14066859377;8109.878351824609;8519;9510.47692376926;9514.953355130792;9534.761630959281;41539.58460415357;46902.53097468981;52971.20543191932;54581;55537.722311388185;51852.481475184766;52489.21266180934;61911.38088619236;70813.64593177207;72691;11763.294202347886;11911.344876031555;12108.636740897788;12207;12578.55975040869;13879.444822206999;14020.579382618538;14510.85489145095;14513.201773902536;15218.315175817337;12375.071869609996;13382.397792099573;13477.39351729155;13921.860781392057;13982.720345592828;11723;11909.702257443632;12254.080943929062;12280.32458214771;13146.474141034634;12013;12815.161712282928;12830.67923301925;12840.97272218239;12905.483780648668;10909.836352454879;11150.219484635998;11236.55053797839;11313.20807543464;11616;11189.160812939224;11301.43492825339;11485.966263036367;11488.597899073493;11491.770164596828 10:29:57 INFO - PROCESS | 1914 | __end_tp_report 10:29:57 INFO - PROCESS | 1914 | __start_cc_report 10:29:57 INFO - PROCESS | 1914 | _x_x_mozilla_cycle_collect,2123 10:29:57 INFO - PROCESS | 1914 | __end_cc_report 10:29:57 INFO - PROCESS | 1914 | __startTimestamp1481912997638__endTimestamp 10:29:57 INFO - PROCESS | 1914 | 10:29:57 INFO - PROCESS | 1914 | ------- Summary: start ------- 10:29:57 INFO - PROCESS | 1914 | Number of tests: 6 10:29:57 INFO - PROCESS | 1914 | 10:29:57 INFO - PROCESS | 1914 | [#0] dojo.html Cycles:165 Average:5740.83 Median:6010.49 stddev:647.24 (10.8%) stddev-sans-first:620.68 10:29:57 INFO - PROCESS | 1914 | Values: 3317.8 3335.3 3346.1 3363.6 3402.4 5658.0 5762.9 5767.1 5786.4 5794.5 5680.1 5769.0 5777.5 5807.7 5813.9 5200.8 5234.7 5263.9 5268.0 5293.8 4291.3 5144.6 5209.2 5325.5 5337.4 5293.7 5296.5 5299.9 5300.0 5301.4 4315.8 5194.1 5225.6 5271.8 5303.2 5164.5 5204.6 5250.3 5251.5 5367.1 5763.6 5832.6 5875.9 5890.0 5906.4 6096.8 6123.5 6125.9 6126.7 6185.0 6115.0 6194.1 6225.0 6230.9 6247.4 5816.1 5822.3 5842.2 5897.1 5898.4 6125.2 6138.4 6192.4 6207.8 6258.6 6140.5 6144.9 6172.0 6172.5 6183.5 6225.7 6226.3 6230.0 6237.4 6240.8 6093.1 6106.2 6107.6 6172.0 6215.5 6175.8 6216.1 6232.5 6245.9 6250.6 4916.6 4928.3 4936.4 4939.0 4973.0 4375.1 4402.6 4444.1 4458.0 4466.5 5646.0 5673.0 5689.8 5755.9 5798.4 5110.8 6179.9 6200.9 6202.2 6224.8 5667.9 5682.5 5687.5 5689.4 5702.0 5281.9 5300.2 5334.0 5378.0 5388.1 6148.5 6258.2 6275.1 6280.6 6328.8 6303.7 6311.6 6320.4 6321.1 6329.5 6190.0 6250.1 6333.3 6350.1 6534.7 6078.7 6120.5 6122.9 6152.0 6217.9 6184.9 6198.7 6223.4 6239.3 6242.9 6098.8 6125.1 6125.3 6125.5 6154.0 6128.6 6132.6 6145.9 6162.6 6165.3 5801.7 5861.0 5915.4 6005.0 6010.5 6077.5 6151.0 6156.0 6159.9 6174.0 5837.4 5945.1 6025.6 6027.0 6057.9 10:29:57 INFO - PROCESS | 1914 | 10:29:57 INFO - PROCESS | 1914 | [#1] ext.html Cycles:180 Average:67242.57 Median:38244.12 stddev:151823.04 (397.0%) stddev-sans-first:152166.24 10:29:57 INFO - PROCESS | 1914 | Values: 498.0 510.7 511.3 517.9 531.0 34851.5 39005.8 39027.6 39046.0 39077.4 39576.0 40148.6 40149.6 40204.6 40237.0 9178.4 9215.2 9339.8 9370.3 9386.8 9316.3 9431.8 9438.7 9440.2 9447.5 9446.6 9472.3 9473.0 9476.2 9477.9 9341.9 9369.5 9406.7 9444.2 9451.7 9318.5 9400.4 9409.3 9419.8 9430.5 38205.0 38495.8 38567.6 38573.6 38681.8 3143.3 3667.7 3792.2 3840.3 3849.9 28353.0 29128.6 29172.6 29751.3 29921.3 3165.7 3190.1 3216.3 3223.6 3230.9 380694.0 384804.0 487402.0 488183.4 489545.0 32120.5 47487.5 57324.9 57381.0 57489.9 33176.7 34349.0 34572.7 34628.8 34691.7 14248.0 14361.2 14386.0 14388.8 14400.6 12095.9 12186.5 12193.6 12198.7 12203.0 7687.2 7692.8 7784.8 7786.1 7793.5 2879.5 3158.6 3226.5 3236.4 3270.8 890.9 909.5 1022.6 1050.2 1061.2 28781.7 29539.6 29542.0 30071.5 30399.8 972.9 1016.6 1065.4 1072.3 1130.0 12969.5 14498.6 15192.0 15397.8 15839.9 631017.0 667329.0 668088.0 668398.0 668514.0 56114.4 57058.0 57076.7 57083.7 57181.0 1355.6 1361.4 1386.1 1386.6 1399.1 28821.9 29023.7 29076.4 29169.0 29988.0 49131.0 49446.5 49483.3 49528.3 49623.0 564601.0 568062.0 568947.0 570670.0 570780.0 26051.2 26253.9 26474.7 26486.0 26501.5 35967.6 45789.1 46028.0 46607.5 47140.1 37465.7 37787.6 38038.0 38283.2 39166.0 36982.3 38025.8 38105.6 38292.8 38967.8 31772.2 31804.1 32318.7 32383.0 37733.8 27768.4 34867.7 34993.7 35555.0 35556.8 25077.0 25487.7 25691.6 25758.2 25960.7 10:29:57 INFO - PROCESS | 1914 | 10:29:57 INFO - PROCESS | 1914 | [#2] jquery.html Cycles:180 Average:33859.21 Median:346.63 stddev:101678.96 (29333.9%) stddev-sans-first:101933.05 10:29:57 INFO - PROCESS | 1914 | Values: 344.3 348.9 367.4 372.9 395.0 14835.0 15080.4 15856.4 20190.0 20653.4 15431.0 18028.6 19918.8 20606.9 20661.3 5637.5 5953.0 5970.2 5977.3 5981.0 4770.7 5860.8 6472.2 6547.8 6599.2 6230.2 6527.5 6530.3 6537.9 6554.9 6432.6 6475.2 6483.1 6520.4 6526.1 5113.7 5381.4 5458.9 5691.1 5694.8 12920.5 13007.4 13022.0 13179.6 13196.0 2142.7 2199.3 2309.4 2324.0 2379.2 9571.7 10054.3 10211.6 10296.7 13930.3 1522.8 1746.6 1781.0 1833.5 1843.5 70010.9 113487.4 113732.4 115414.4 116414.7 11178.6 11380.5 11678.0 11743.4 11895.7 14671.9 14683.2 14709.0 14711.9 14717.2 8300.1 8373.3 8451.6 8459.2 8558.0 7944.6 8262.2 8396.6 8437.1 8437.7 4424.6 4491.3 4524.9 4571.7 4587.2 1835.6 1877.9 1885.6 1889.4 1891.3 371.7 384.7 397.6 809.5 927.2 10982.8 11392.6 11491.5 11736.1 12381.1 423.7 424.4 428.3 429.0 438.9 10994.5 12002.0 12135.8 12187.9 12201.5 542929.0 622511.0 623997.0 631357.0 638392.0 82659.0 100327.5 106802.0 116298.4 133972.0 890.1 945.3 1004.5 1007.3 1008.6 15422.8 18820.0 19372.8 19434.5 19486.0 54414.7 55008.4 55768.7 55958.6 57450.0 76223.0 76299.9 77998.6 79940.6 83809.6 6769.8 7256.0 7661.6 8210.3 8393.0 12157.4 12173.5 12706.6 13137.1 13228.8 9190.9 10813.6 11265.9 11566.1 11680.3 9268.9 10733.7 11034.7 11150.1 11225.9 11311.4 11460.8 11530.3 11664.8 12021.4 7303.6 7503.1 7562.7 7700.7 7847.4 7705.9 8705.5 8746.8 10415.4 10985.2 10:29:57 INFO - PROCESS | 1914 | 10:29:57 INFO - PROCESS | 1914 | [#3] mootools.html Cycles:180 Average:5387.49 Median:4959.40 stddev:2978.29 (60.1%) stddev-sans-first:2970.02 10:29:57 INFO - PROCESS | 1914 | Values: 1200.9 1448.7 1547.2 1571.8 1748.8 8029.5 8353.2 8382.5 8384.2 8413.0 7386.1 7642.8 7972.2 8208.1 8229.3 2264.8 2276.7 2277.4 2287.8 2294.2 2343.0 2373.6 2375.1 2381.8 2382.1 2317.4 2324.1 2345.9 2352.0 2357.4 2308.8 2314.8 2332.9 2333.8 2340.8 2284.6 2345.2 2349.5 2349.6 2350.1 8242.3 8323.0 8342.3 8342.6 8351.5 4994.1 5157.2 5162.6 5162.6 5166.8 3202.3 3365.6 3452.1 3535.9 3537.5 1015.7 1059.8 1083.7 1097.7 1103.9 10731.0 10810.6 10846.6 10847.3 10850.9 4428.3 4612.4 4700.8 4725.2 4738.5 9242.8 9248.3 9266.9 9268.5 9269.8 5087.4 5115.7 5121.1 5122.2 5127.2 3502.6 4282.3 4446.5 4550.7 4710.3 3731.5 3759.0 3821.0 3864.7 3866.8 1248.6 1259.3 1259.3 1272.2 1274.8 763.5 831.4 872.4 891.3 909.3 5464.0 5487.9 5536.6 5573.8 5669.5 424.8 428.4 444.6 448.5 451.0 3686.6 3703.2 3705.1 3768.7 3798.1 9738.7 9930.2 10050.5 10087.3 10090.7 9981.7 10030.5 10044.3 10055.3 10071.9 7235.8 7587.8 7708.5 7800.9 7814.8 5528.5 5578.4 5652.9 5666.3 5751.2 9164.6 9167.5 9205.4 9372.3 9432.6 9211.2 9384.4 9503.9 9532.5 9561.6 6540.5 6717.7 6798.9 7068.8 7242.1 7735.4 7943.7 7952.7 7955.8 7956.1 7022.8 7094.9 7145.4 7167.9 7286.1 6735.6 6784.8 6976.8 7000.7 7033.5 6372.6 6529.5 6625.9 6637.2 6709.0 4095.6 4767.6 4773.3 4815.4 4820.8 4711.2 4747.5 4769.8 4926.8 4992.0 10:29:57 INFO - PROCESS | 1914 | 10:29:57 INFO - PROCESS | 1914 | [#4] prototype.html Cycles:180 Average:4414.84 Median:5000.14 stddev:1353.98 (27.1%) stddev-sans-first:1321.70 10:29:57 INFO - PROCESS | 1914 | Values: 278.2 297.6 308.3 309.7 312.1 5068.8 5260.7 5260.9 5278.2 5313.6 4114.6 4287.6 5097.6 5150.7 5216.8 3353.3 3933.7 3986.3 4039.0 4068.9 3386.5 3975.9 4044.8 4069.9 4078.6 3362.9 3871.3 3975.0 4035.5 4052.6 3512.4 3789.5 3842.8 4001.7 4021.5 3042.9 3505.8 3509.3 3512.2 3517.4 4126.9 4853.7 5060.5 5094.7 5099.9 4568.7 4615.9 4619.2 4629.3 4654.2 5508.9 5510.3 5518.5 5527.6 5540.0 3870.4 3870.7 3881.6 3889.4 3911.2 4760.8 5459.5 5667.1 5673.4 5740.5 3794.6 3894.8 3918.5 3934.7 3942.9 3192.8 3335.8 3344.1 3588.7 3674.0 4276.4 4578.9 4592.6 4634.0 4667.3 4371.3 4573.8 5496.3 5497.3 5556.9 1143.4 1144.0 1161.7 1279.9 1288.6 810.8 842.9 850.0 870.2 892.2 4408.2 4884.7 4896.5 4919.3 4984.8 5406.6 5443.9 5461.9 5466.5 5502.2 4888.7 4977.9 5151.5 5159.9 5260.4 2614.8 2678.8 2858.6 2878.5 2885.9 4842.3 5272.4 5292.6 5371.4 5688.5 5119.9 5255.0 5352.5 5456.1 5490.9 5082.2 5091.3 5652.7 5658.1 5683.2 5357.2 5407.4 5425.8 5428.5 5713.8 4841.9 5459.5 5701.5 5707.7 5714.7 4449.7 4955.7 5157.9 5270.2 5504.0 3632.0 3733.6 3824.9 3839.4 3883.7 5678.3 5679.7 5694.5 5704.7 5710.1 5499.7 5499.9 5518.8 5528.6 5530.6 5278.4 5539.3 5544.0 5585.6 5616.3 4548.0 5158.6 5475.3 5478.9 5516.0 5382.5 5391.4 5517.5 5535.4 5639.5 4161.7 4855.2 5015.5 5066.3 5425.6 10:29:57 INFO - PROCESS | 1914 | 10:29:57 INFO - PROCESS | 1914 | [#5] yui.html Cycles:180 Average:19819.38 Median:4525.77 stddev:28294.76 (625.2%) stddev-sans-first:28337.58 10:29:57 INFO - PROCESS | 1914 | Values: 665.7 679.4 695.2 756.5 776.7 14073.8 14539.0 14592.0 14692.0 15476.5 14832.9 14961.0 14996.3 14998.8 15014.4 806.5 829.6 832.0 832.3 832.7 652.1 670.4 671.3 804.4 824.2 863.3 887.2 889.1 890.9 898.1 861.0 863.0 863.2 865.7 865.8 510.2 522.9 620.2 635.6 637.5 12395.9 14069.8 14341.9 14604.9 14612.6 13350.1 13601.7 13675.7 13786.2 13789.3 12163.5 12434.0 12453.8 12508.7 12842.4 4355.7 4385.8 4450.4 4451.8 4535.9 92498.0 94061.0 95947.0 103313.0 103464.5 10440.4 10693.8 10791.5 11033.8 11314.7 19592.3 20771.0 20800.4 20943.9 20948.9 7870.8 7895.4 7955.9 7976.4 8201.4 4143.7 4212.2 4304.3 4420.3 4515.6 3353.9 3476.5 3484.9 3488.9 3489.6 642.9 646.8 653.8 677.2 677.4 585.6 634.7 651.1 652.5 654.0 8695.3 9077.3 9080.7 9106.6 9107.2 562.4 583.9 602.4 612.4 642.3 3982.8 4228.6 4678.0 4702.9 4784.2 74532.6 79737.0 84814.3 110481.0 110870.0 76846.6 87505.1 90795.0 95461.0 96168.6 81859.0 82793.0 83781.2 85363.0 85933.1 8109.9 8519.0 9510.5 9515.0 9534.8 41539.6 46902.5 52971.2 54581.0 55537.7 51852.5 52489.2 61911.4 70813.6 72691.0 11763.3 11911.3 12108.6 12207.0 12578.6 13879.4 14020.6 14510.9 14513.2 15218.3 12375.1 13382.4 13477.4 13921.9 13982.7 11723.0 11909.7 12254.1 12280.3 13146.5 12013.0 12815.2 12830.7 12841.0 12905.5 10909.8 11150.2 11236.6 11313.2 11616.0 11189.2 11301.4 11486.0 11488.6 11491.8 10:29:57 INFO - PROCESS | 1914 | -------- Summary: end -------- 10:29:57 INFO - PROCESS | 1914 | 10:29:58 INFO - TEST-INFO | 1914: exit 0 10:29:58 INFO - TEST-OK | dromaeo_css | took 1120651ms 10:29:58 INFO - TEST-START | kraken 10:29:58 INFO - operating with platform_type : mac_ 10:29:58 INFO - Initialising browser for kraken test... 10:29:58 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmptZNzjN/profile http://localhost:51551/getInfo.html) 10:30:00 INFO - PROCESS | 1919 | __metrics Screen width/height:1600/1200 10:30:00 INFO - PROCESS | 1919 | colorDepth:24 10:30:00 INFO - PROCESS | 1919 | Browser inner width/height: 1024/689 10:30:00 INFO - PROCESS | 1919 | __metrics 10:30:00 INFO - PROCESS | 1919 | 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:30:00 INFO - PROCESS | 1919 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:30:01 INFO - TEST-INFO | 1919: exit 0 10:30:01 INFO - Browser initialized. 10:30:01 INFO - Running cycle 1/1 for kraken test... 10:30:01 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmptZNzjN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:30:14 INFO - PROCESS | 1921 | RSS: Main: 222515200 10:30:14 INFO - PROCESS | 1921 | 10:30:16 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51551/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51551/tests/kraken/driver.html?testName=audio-beat-detection) 10:30:17 INFO - PROCESS | 1921 | RSS: Main: 272265216 10:30:17 INFO - PROCESS | 1921 | 10:30:20 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51551/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51551/tests/kraken/driver.html?testName=audio-dft) 10:30:20 INFO - PROCESS | 1921 | RSS: Main: 284667904 10:30:20 INFO - PROCESS | 1921 | 10:30:24 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51551/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51551/tests/kraken/driver.html?testName=audio-fft) 10:30:24 INFO - PROCESS | 1921 | RSS: Main: 379445248 10:30:24 INFO - PROCESS | 1921 | 10:30:26 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51551/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51551/tests/kraken/driver.html?testName=audio-oscillator) 10:30:27 INFO - PROCESS | 1921 | RSS: Main: 282025984 10:30:27 INFO - PROCESS | 1921 | 10:30:29 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51551/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51551/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:30:30 INFO - PROCESS | 1921 | RSS: Main: 284606464 10:30:30 INFO - PROCESS | 1921 | 10:30:32 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51551/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51551/tests/kraken/driver.html?testName=imaging-darkroom) 10:30:33 INFO - PROCESS | 1921 | RSS: Main: 325361664 10:30:33 INFO - PROCESS | 1921 | 10:30:36 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51551/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51551/tests/kraken/driver.html?testName=imaging-desaturate) 10:30:37 INFO - PROCESS | 1921 | RSS: Main: 324435968 10:30:37 INFO - PROCESS | 1921 | 10:30:39 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51551/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51551/tests/kraken/driver.html?testName=json-parse-financial) 10:30:40 INFO - PROCESS | 1921 | RSS: Main: 321650688 10:30:40 INFO - PROCESS | 1921 | 10:30:41 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51551/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51551/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:30:42 INFO - PROCESS | 1921 | RSS: Main: 300232704 10:30:42 INFO - PROCESS | 1921 | 10:30:44 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51551/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51551/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:30:45 INFO - PROCESS | 1921 | RSS: Main: 300978176 10:30:45 INFO - PROCESS | 1921 | 10:30:47 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51551/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51551/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:30:48 INFO - PROCESS | 1921 | RSS: Main: 303112192 10:30:48 INFO - PROCESS | 1921 | 10:30:50 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51551/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51551/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:30:51 INFO - PROCESS | 1921 | RSS: Main: 310759424 10:30:51 INFO - PROCESS | 1921 | 10:30:54 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51551/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51551/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:30:55 INFO - PROCESS | 1921 | RSS: Main: 310460416 10:30:55 INFO - PROCESS | 1921 | 10:30:56 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51551/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51551/tests/kraken/driver.html?testName=ai-astar) 10:30:57 INFO - PROCESS | 1921 | RSS: Main: 354398208 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | __start_tp_report 10:30:57 INFO - PROCESS | 1921 | _x_x_mozilla_page_load 10:30:57 INFO - PROCESS | 1921 | _x_x_mozilla_page_load_details 10:30:57 INFO - PROCESS | 1921 | |i|pagename|runs| 10:30:57 INFO - PROCESS | 1921 | |0;ai-astar;116;115;115;114;113;115;113;113;118;160 10:30:57 INFO - PROCESS | 1921 | |1;audio-beat-detection;174;110;110;144;109;110;109;109;143;108 10:30:57 INFO - PROCESS | 1921 | |2;audio-dft;184;184;185;181;183;184;181;183;183;184 10:30:57 INFO - PROCESS | 1921 | |3;audio-fft;85;62;78;112;79;49;73;74;110;82 10:30:57 INFO - PROCESS | 1921 | |4;audio-oscillator;87;70;95;71;68;69;90;69;69;93 10:30:57 INFO - PROCESS | 1921 | |5;imaging-gaussian-blur;89;85;128;88;90;86;91;88;90;86 10:30:57 INFO - PROCESS | 1921 | |6;imaging-darkroom;98;95;95;95;94;95;94;95;94;95 10:30:57 INFO - PROCESS | 1921 | |7;imaging-desaturate;83;81;82;80;82;81;85;85;83;83 10:30:57 INFO - PROCESS | 1921 | |8;json-parse-financial;53;53;55;54;56;58;58;58;58;58 10:30:57 INFO - PROCESS | 1921 | |9;json-stringify-tinderbox;65;74;73;75;76;72;71;76;72;73 10:30:57 INFO - PROCESS | 1921 | |10;stanford-crypto-aes;84;88;101;100;76;84;84;93;106;84 10:30:57 INFO - PROCESS | 1921 | |11;stanford-crypto-ccm;141;140;142;141;141;144;144;144;185;151 10:30:57 INFO - PROCESS | 1921 | |12;stanford-crypto-pbkdf2;165;164;171;166;165;168;164;166;165;165 10:30:57 INFO - PROCESS | 1921 | |13;stanford-crypto-sha256-iterative;67;85;86;83;70;67;66;67;66;67 10:30:57 INFO - PROCESS | 1921 | __end_tp_report 10:30:57 INFO - PROCESS | 1921 | __start_cc_report 10:30:57 INFO - PROCESS | 1921 | _x_x_mozilla_cycle_collect,1992 10:30:57 INFO - PROCESS | 1921 | __end_cc_report 10:30:57 INFO - PROCESS | 1921 | __startTimestamp1481913057172__endTimestamp 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | ------- Summary: start ------- 10:30:57 INFO - PROCESS | 1921 | Number of tests: 14 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | [#0] ai-astar Cycles:10 Average:119.20 Median:115.00 stddev:14.42 (12.5%) stddev-sans-first:15.25 10:30:57 INFO - PROCESS | 1921 | Values: 116.0 115.0 115.0 114.0 113.0 115.0 113.0 113.0 118.0 160.0 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | [#1] audio-beat-detection Cycles:10 Average:122.60 Median:110.00 stddev:23.00 (20.9%) stddev-sans-first:15.10 10:30:57 INFO - PROCESS | 1921 | Values: 174.0 110.0 110.0 144.0 109.0 110.0 109.0 109.0 143.0 108.0 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:1.32 (0.7%) stddev-sans-first:1.36 10:30:57 INFO - PROCESS | 1921 | Values: 184.0 184.0 185.0 181.0 183.0 184.0 181.0 183.0 183.0 184.0 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | [#3] audio-fft Cycles:10 Average:80.40 Median:76.00 stddev:19.22 (25.3%) stddev-sans-first:20.32 10:30:57 INFO - PROCESS | 1921 | Values: 85.0 62.0 78.0 112.0 79.0 49.0 73.0 74.0 110.0 82.0 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | [#4] audio-oscillator Cycles:10 Average:78.10 Median:79.00 stddev:11.52 (14.6%) stddev-sans-first:11.76 10:30:57 INFO - PROCESS | 1921 | Values: 87.0 70.0 95.0 71.0 68.0 69.0 90.0 69.0 69.0 93.0 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:88.50 stddev:12.77 (14.4%) stddev-sans-first:13.49 10:30:57 INFO - PROCESS | 1921 | Values: 89.0 85.0 128.0 88.0 90.0 86.0 91.0 88.0 90.0 86.0 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:1.15 (1.2%) stddev-sans-first:0.50 10:30:57 INFO - PROCESS | 1921 | Values: 98.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 94.0 95.0 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | [#7] imaging-desaturate Cycles:10 Average:82.50 Median:83.00 stddev:1.65 (2.0%) stddev-sans-first:1.74 10:30:57 INFO - PROCESS | 1921 | Values: 83.0 81.0 82.0 80.0 82.0 81.0 85.0 85.0 83.0 83.0 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:58.00 stddev:2.18 (3.8%) stddev-sans-first:2.01 10:30:57 INFO - PROCESS | 1921 | Values: 53.0 53.0 55.0 54.0 56.0 58.0 58.0 58.0 58.0 58.0 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | [#9] json-stringify-tinderbox Cycles:10 Average:72.70 Median:73.50 stddev:3.20 (4.4%) stddev-sans-first:1.81 10:30:57 INFO - PROCESS | 1921 | Values: 65.0 74.0 73.0 75.0 76.0 72.0 71.0 76.0 72.0 73.0 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:84.00 stddev:9.60 (11.4%) stddev-sans-first:9.94 10:30:57 INFO - PROCESS | 1921 | Values: 84.0 88.0 101.0 100.0 76.0 84.0 84.0 93.0 106.0 84.0 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:144.00 stddev:13.61 (9.5%) stddev-sans-first:14.25 10:30:57 INFO - PROCESS | 1921 | Values: 141.0 140.0 142.0 141.0 141.0 144.0 144.0 144.0 185.0 151.0 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.90 Median:165.50 stddev:2.13 (1.3%) stddev-sans-first:2.24 10:30:57 INFO - PROCESS | 1921 | Values: 165.0 164.0 171.0 166.0 165.0 168.0 164.0 166.0 165.0 165.0 10:30:57 INFO - PROCESS | 1921 | 10:30:57 INFO - PROCESS | 1921 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:68.50 stddev:8.57 (12.5%) stddev-sans-first:8.86 10:30:57 INFO - PROCESS | 1921 | Values: 67.0 85.0 86.0 83.0 70.0 67.0 66.0 67.0 66.0 67.0 10:30:57 INFO - PROCESS | 1921 | -------- Summary: end -------- 10:30:57 INFO - PROCESS | 1921 | 10:30:58 INFO - TEST-INFO | 1921: exit 0 10:30:58 INFO - TEST-OK | kraken | took 59938ms 10:30:58 INFO - SUITE-END | took 1180s 10:30:58 INFO - Completed test suite (00:19:40) 10:30:58 INFO - javascript benchmark 10:30:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3317.767756257062, 3335.2662414268866, 3346.079828047285, 3363.604104296717, 3402.353552824963, 5658.009848276551, 5762.855928601787, 5767.106098554731, 5786.363500015001, 5794.507466865316, 5680.147977803332, 5769.0, 5777.480026797587, 5807.73865176067, 5813.941860581393, 5200.843974680756, 5234.685918844882, 5263.921041184385, 5268.025415298163, 5293.841184764463, 4291.334843099309, 5144.614153938451, 5209.244659524366, 5325.520703136717, 5337.412884582693, 5293.655912365684, 5296.523312901838, 5299.920501192485, 5300.01949639318, 5301.363836339645, 4315.805788739514, 5194.091034069042, 5225.581953443715, 5271.789128434858, 5303.204519322094, 5164.457731938168, 5204.583633309364, 5250.291210686585, 5251.474852514756, 5367.141257398836, 5763.567732420085, 5832.62087964281, 5875.9118613220835, 5890.0, 5906.3798301178185, 6096.817095487142, 6123.526657168159, 6125.907024958145, 6126.731429653759, 6185.010398336244, 6115.027386288435, 6194.070889366586, 6224.9688751556405, 6230.906536401958, 6247.406496382836, 5816.083577500352, 5822.3304320003745, 5842.152887831241, 5897.144913987448, 5898.439648233411, 6125.215553761287, 6138.401218689926, 6192.442680158807, 6207.81376558704, 6258.593191442633, 6140.548102625561, 6144.924638188389, 6172.012478003588, 6172.537059720539, 6183.536234782409, 6225.688715564203, 6226.25284965807, 6230.034344676583, 6237.407446292595, 6240.7503699852405, 6093.086037094383, 6106.164791923452, 6107.61364822091, 6172.0, 6215.533834962396, 6175.814725558241, 6216.129741836146, 6232.501399887998, 6245.906311405332, 6250.593711408744, 4916.631252656065, 4928.310036594879, 4936.3829521309835, 4939.0, 4972.975135124301, 4375.059362237071, 4402.559744025636, 4444.088961762831, 4458.019235768126, 4466.531014243523, 5646.011947909099, 5673.0, 5689.829305120853, 5755.884882302331, 5798.420157984167, 5110.821121260738, 6179.876402471926, 6200.937990620127, 6202.193714817045, 6224.844378890473, 5667.886642267132, 5682.460166284196, 5687.488126068591, 5689.374168841424, 5702.030654788696, 5281.867953301121, 5300.231466437345, 5333.973330133324, 5378.0, 5388.084025715638, 6148.47737942262, 6258.217722784652, 6275.121482992385, 6280.560360774702, 6328.810135695937, 6303.6532990685955, 6311.589746666452, 6320.367963203643, 6321.083442900662, 6329.49364050884, 6189.96905015472, 6250.124982502362, 6333.303336633059, 6350.079238510482, 6534.673266336759, 6078.7264573093435, 6120.510359171345, 6122.877542449215, 6152.046432802923, 6217.906731398942, 6184.876302474016, 6198.659073750987, 6223.408776166257, 6239.344868788712, 6242.875142497215, 6098.752142885754, 6125.142480052886, 6125.264968203844, 6125.510168486406, 6154.0, 6128.63228206309, 6132.570720049732, 6145.907811382744, 6162.630242185483, 6165.321814600301, 5801.651900885791, 5860.970695146668, 5915.438033386993, 6005.039193728982, 6010.4891084258325, 6077.453029227391, 6151.046587778901, 6156.045812899008, 6159.876802463836, 6174.0, 5837.3870743571315, 5945.137954996588, 6025.578209525466, 6027.0, 6057.878842423215], "value": 5699.127287401644, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.990040199196, 510.6655140882722, 511.31994447384983, 517.8938317644878, 530.989380212396, 34851.477227841606, 39005.80497097518, 39027.60972390275, 39046.0, 39077.41383879244, 39576.0, 40148.59851401492, 40149.59850401495, 40204.59795402045, 40237.0, 9178.403403778766, 9215.17063464288, 9339.766505837375, 9370.344075914689, 9386.81226375472, 9316.301277404187, 9431.81136377272, 9438.669646562372, 9440.244780417584, 9447.527623618791, 9446.622135114587, 9472.289578281621, 9472.952635236814, 9476.241900647932, 9477.905220947805, 9341.859872101924, 9369.484678342722, 9406.717798466058, 9444.244460443148, 9451.716448506488, 9318.509038553799, 9400.435973841591, 9409.294302927308, 9419.764505887339, 9430.528473576362, 38205.0, 38495.807520962495, 38567.614323856964, 38573.61426385756, 38681.80659096715, 3143.2613335866054, 3667.743257971943, 3792.2119406262755, 3840.347140986039, 3849.903752406184, 28353.0, 29128.563071553945, 29172.562411563842, 29751.25621859449, 29921.25196870074, 3165.667604901475, 3190.122716253026, 3216.3245718399166, 3223.629282632504, 3230.8869189578254, 380694.0, 384804.0, 487402.0, 488183.3544993656, 489545.0, 32120.518192227137, 47487.525124749, 57324.853502929705, 57381.0, 57489.85020299571, 33176.66823331784, 34349.0, 34572.65427345745, 34628.826855866064, 34691.65308346935, 14248.0, 14361.210133442552, 14385.992980491264, 14388.784168237486, 14400.567982960529, 12095.879041209651, 12186.451609677586, 12193.573224937085, 12198.695032624255, 12203.0, 7687.192844751333, 7692.8461430772195, 7784.84430311402, 7786.104597971193, 7793.493422927491, 2879.481693295185, 3158.6051743532057, 3226.548283240348, 3236.3527294541186, 3270.787398819069, 890.8797312362877, 909.5315912305168, 1022.5756311130798, 1050.2490719135856, 1061.2200032975757, 28781.712182878324, 29539.556906646416, 29541.966031188804, 30071.548926766118, 30399.848000760296, 972.8714690958595, 1016.588281745894, 1065.3607835298758, 1072.340510585984, 1129.9887001130048, 12969.481220751253, 14498.565043048726, 15192.012519186217, 15397.84602153964, 15839.920800395925, 631017.0, 667329.0, 668088.0, 668398.0, 668514.0, 56114.43885561174, 57058.0, 57076.71461642665, 57083.71458142766, 57181.0, 1355.613650109714, 1361.4213959067356, 1386.0990356268305, 1386.5770939863237, 1399.0905911157624, 28821.855890720413, 29023.7097629021, 29076.418471630026, 29169.0, 29988.0, 49131.0, 49446.50553494419, 49483.25775113304, 49528.257076144604, 49623.0, 564601.0, 568062.0, 568947.0, 570670.0, 570780.0, 26051.218463446126, 26253.868730656224, 26474.735252648, 26486.0, 26501.469970600094, 35967.64032359748, 45789.08421831478, 46028.0, 46607.53392466032, 47140.05719885651, 37465.65007544562, 37787.62212377841, 38038.049048774, 38283.2343353137, 39166.02084947899, 36982.26035479329, 38025.80987095047, 38105.61894381021, 38292.80853595714, 38967.805160974014, 31772.205694857814, 31804.05593848294, 32318.676813231566, 32383.0, 37733.81133094317, 27768.44463110686, 34867.65132348644, 34993.65006349904, 35555.0, 35556.82221588876, 25077.0, 25487.74512254854, 25691.614625781003, 25758.227253182435, 25960.74039259583], "value": 18214.068728047456, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.314813521093, 348.9371913055652, 367.36629314432605, 372.85458671118266, 394.9842006319747, 14835.0, 15080.396784128636, 15856.36574537017, 20190.0, 20653.38039858803, 15430.996985195954, 18028.63942721145, 19918.800811991874, 20606.89696551519, 20661.27685531007, 5637.492625663689, 5952.9702351488395, 5970.1940238067955, 5977.252843394575, 5980.983232850426, 4770.689905156171, 5860.794872179475, 6472.158619379474, 6547.77082802102, 6599.175103112111, 6230.190075290228, 6527.543071984963, 6530.346965303479, 6537.869242615168, 6554.901676474857, 6432.646204458753, 6475.19060117483, 6483.059956306309, 6520.4457621102265, 6526.118973938505, 5113.693178409308, 5381.3811411687375, 5458.8908221835345, 5691.06097493913, 5694.829155125353, 12920.54778082772, 13007.414666340037, 13022.0, 13179.60461186166, 13196.0, 2142.7428708554917, 2199.3182113544854, 2309.4226443389152, 2323.9767602323927, 2379.179183181817, 9571.712848614552, 10054.296199266055, 10211.642592509226, 10296.691099267035, 13930.30348482582, 1522.8020357353585, 1746.5982823950435, 1780.9670391173088, 1833.504953662517, 1843.5483306589838, 70010.89967300989, 113487.43256283748, 113732.4313378436, 115414.42292788567, 116414.6717065654, 11178.608748693756, 11380.487878045507, 11678.0, 11743.354115523729, 11895.702607434883, 14671.899607529478, 14683.19242441676, 14709.0, 14711.852881471048, 14717.190554519604, 8300.128486508951, 8373.330133589297, 8451.619677114544, 8459.238668519863, 8557.957210214034, 7944.6027698614835, 8262.17378262169, 8396.622152003174, 8437.114103019218, 8437.704680336159, 4424.579664931826, 4491.30384790358, 4524.88687782808, 4571.702839315434, 4587.151376995266, 1835.6420498003029, 1877.915493802782, 1885.566319746466, 1889.4142815726893, 1891.2718603337814, 371.6952099278566, 384.6884023940617, 397.5785667192785, 809.5142914251402, 927.174814415171, 10982.780344393068, 11392.601258955896, 11491.54033838637, 11736.119791015708, 12381.133320667646, 423.6780047164116, 424.4058318354267, 428.3403558519907, 429.0282510114593, 438.90563528841454, 10994.526231597827, 12002.0, 12135.757284854253, 12187.939060304641, 12201.511939522497, 542929.0, 622511.0, 623997.0, 631357.0, 638392.0, 82659.0, 100327.49836250771, 106802.0, 116298.41850790862, 133971.9904201438, 890.0743227043931, 945.3052006774877, 1004.5379125602159, 1007.2848277722752, 1008.5965613754411, 15422.84577154259, 18819.964901930525, 19372.806271937665, 19434.514137146685, 19486.0, 54414.727926360116, 55008.449915500336, 55768.721156393956, 55958.601034974454, 57450.0, 76223.0, 76299.8555021664, 77998.61000695187, 79940.60029699815, 83809.58095209485, 6769.830754231184, 7256.0, 7661.578613176329, 8210.343172546302, 8392.950881139857, 12157.392130393622, 12173.513059477522, 12706.555270565486, 13137.146085504412, 13228.801567976296, 9190.862137067816, 10813.56745730162, 11265.887341126483, 11566.07471402269, 11680.299182049104, 9268.934072581469, 10733.731656708645, 11034.724131896766, 11150.10799136051, 11225.943870280596, 11311.434428278388, 11460.770784584429, 11530.308181509135, 11664.825027624762, 12021.398930053287, 7303.598302093435, 7503.09962804467, 7562.732573179509, 7700.730474433368, 7847.411444141826, 7705.922940770521, 8705.47767133974, 8746.781330466534, 10415.42715150674, 10985.231033827555], "value": 8794.54649352165, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1200.921940073895, 1448.710647523704, 1547.195458361652, 1571.8192407873098, 1748.8426041656248, 8029.478083924548, 8353.248207661309, 8382.538960357186, 8384.16158384162, 8413.032501262342, 7386.0767404074495, 7642.808929776758, 7972.202779722025, 8208.09710931797, 8229.300509456703, 2264.807491363232, 2276.692646492727, 2277.36233854521, 2287.8055365293885, 2294.2314324701247, 2342.976570234293, 2373.560891235127, 2375.1212051540956, 2381.761823817622, 2382.1424287256573, 2317.385892738426, 2324.140068174769, 2345.94135146621, 2351.9529609407887, 2357.434215788207, 2308.8152947764306, 2314.8148148148107, 2332.8950197240997, 2333.8482998605036, 2340.847844890077, 2284.588774020676, 2345.2495201535517, 2349.5418393413293, 2349.612313968193, 2350.059976009593, 8242.258196762261, 8323.001239851195, 8342.332613390914, 8342.582870856433, 8351.540665263408, 4994.126027945095, 5157.200633901752, 5162.638615296931, 5162.638615296931, 5166.819161329336, 3202.327511222646, 3365.6297807241185, 3452.085197422685, 3535.9116022099397, 3537.451694987281, 1015.7105225010837, 1059.8145324568247, 1083.712816103733, 1097.7310558913039, 1103.9116870650328, 10731.0, 10810.567577296899, 10846.620368287151, 10847.294925829796, 10850.945745271301, 4428.2914733642465, 4612.423447069116, 4700.835470758576, 4725.243960966229, 4738.549837765407, 9242.81514369709, 9248.260139188984, 9266.907330926739, 9268.536573171314, 9269.768255793659, 5087.440381558025, 5115.7442127893455, 5121.052605268037, 5122.206058061007, 5127.2052831811125, 3502.614712381636, 4282.293421585404, 4446.5331140230455, 4550.704204226715, 4710.29345598163, 3731.533558305212, 3759.022654109951, 3820.9808950955066, 3864.729468937204, 3866.845326186977, 1248.5505218121468, 1259.3388471052588, 1259.345140526939, 1272.249372870011, 1274.7577960187534, 763.5495057915746, 831.3515457942814, 872.389327470773, 891.2558014058334, 909.3225546967551, 5464.039781956604, 5487.917681234785, 5536.612437129363, 5573.832785016456, 5669.518090962232, 424.8279446824041, 428.400239664466, 444.5643269595814, 448.52008351064046, 450.9549045095464, 3686.557613086447, 3703.166787472797, 3705.073731567108, 3768.698504119664, 3798.1454172810895, 9738.659146929824, 9930.155936745314, 10050.547725352231, 10087.344322619007, 10090.69727908164, 9981.700548983543, 10030.498475076218, 10044.347117437343, 10055.346402483816, 10071.949640251752, 7235.819104522429, 7587.772366829004, 7708.537487750753, 7800.882986755203, 7814.843703125905, 5528.530074943514, 5578.442155784552, 5652.85867853307, 5666.291713535808, 5751.166080918159, 9164.587593558439, 9167.541622918961, 9205.355625106178, 9372.250219982521, 9432.62269509212, 9211.17099461025, 9384.436933783994, 9503.857442138511, 9532.52337383142, 9561.617535298788, 6540.50946179029, 6717.731290748511, 6798.864022719419, 7068.82327941785, 7242.058532390755, 7735.4198435118715, 7943.682252709827, 7952.721654742057, 7955.84088318242, 7956.085050219299, 7022.754203602849, 7094.929050709633, 7145.356917877416, 7167.8566428672175, 7286.125664920243, 6735.6295403753265, 6784.830379240559, 6976.7558135465015, 7000.684969176501, 7033.472489563201, 6372.55392122547, 6529.5102867284195, 6625.900611490735, 6637.236717777518, 6709.0, 4095.5904409558807, 4767.642426818072, 4773.284007398918, 4815.398075240595, 4820.758962051813, 4711.2226482629985, 4747.501512341154, 4769.785359658755, 4926.827561035347, 4991.976644787737], "value": 4293.911757712456, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.2029485523973, 297.64134218266986, 308.3370752881305, 309.69959139634545, 312.05291938965246, 5068.797248110071, 5260.736963151846, 5260.9210861837055, 5278.234655974881, 5313.57491400688, 4114.567970363114, 4287.571242875711, 5097.59219262459, 5150.69095854249, 5216.843494695156, 3353.3460975109742, 3933.724639275252, 3986.302397080514, 4039.010442441606, 4068.901396622925, 3386.5089562013495, 3975.9006024849323, 4044.777537235451, 4069.9186016279655, 4078.6125318094732, 3362.865485380582, 3871.2838124946975, 3975.0, 4035.47538819953, 4052.5947405259535, 3512.4028915084496, 3789.4694742736037, 3842.846286148551, 4001.659858911996, 4021.5375231848275, 3042.878284868603, 3505.7922001368715, 3509.2805974775365, 3512.1921957949817, 3517.402041652925, 4126.8761937141935, 4853.660243782937, 5060.51925067118, 5094.745262736848, 5099.9490005100215, 4568.675084225571, 4615.9383341831235, 4619.214733495314, 4629.328747331651, 4654.232051711463, 5508.862278443031, 5510.338759348904, 5518.503334699896, 5527.55779537636, 5540.0, 3870.3681496582312, 3870.6709929656013, 3881.573026967031, 3889.358255887775, 3911.2177564487215, 4760.809567617292, 5459.535939445152, 5667.093265077568, 5673.375928647845, 5740.512056475163, 3794.6015668354426, 3894.805259737002, 3918.529776426847, 3934.744241624285, 3942.8817135485983, 3192.8403579820915, 3335.832458639457, 3344.130526063241, 3588.69496092835, 3674.0080178351695, 4276.444062271947, 4578.931316030262, 4592.553345696162, 4634.0, 4667.346571479996, 4371.344298355272, 4573.77548828082, 5496.285482887199, 5497.32331638854, 5556.860843527067, 1143.4397145398862, 1143.9942800285946, 1161.7163034846542, 1279.942402591867, 1288.6327396691897, 810.7703814510097, 842.8819965204876, 849.9830003399898, 870.2298465857762, 892.1836519584638, 4408.184485870124, 4884.7313397762755, 4896.46138924718, 4919.335889654851, 4984.7878542631415, 5406.621536492407, 5443.863903402367, 5461.863453413696, 5466.5240385096595, 5502.174673798882, 4888.706677599355, 4977.850664480072, 5151.484851514893, 5159.916417552281, 5260.368755749335, 2614.7908167346945, 2678.7587019706375, 2858.5992863496735, 2878.4674835155565, 2885.898993535216, 4842.297866809293, 5272.446393128743, 5292.576593872481, 5371.435999220103, 5688.544916406678, 5119.897602047939, 5255.02781985334, 5352.5450336721815, 5456.099743542271, 5490.917636235459, 5082.161443361804, 5091.261767043758, 5652.658237965084, 5658.132816170654, 5683.238196159204, 5357.151782634985, 5407.378151512469, 5425.782968681367, 5428.45715428454, 5713.771449141987, 4841.8547443576745, 5459.454054594509, 5701.515371193329, 5707.714614269187, 5714.714264286852, 4449.688521803572, 4955.72743499111, 5157.948420515747, 5270.209468579744, 5503.972480137573, 3631.9636803631624, 3733.637222413745, 3824.9043773904764, 3839.3665045267217, 3883.7087218458164, 5678.290213723285, 5679.716014199356, 5694.487496125369, 5704.657720536781, 5710.086386178192, 5499.697516636624, 5499.890002200013, 5518.779248830162, 5528.585356098389, 5530.585206109477, 5278.439956621112, 5539.25220095282, 5544.029794785977, 5585.5531557476115, 5616.297962754656, 4547.999440123168, 5158.638895277445, 5475.315585551806, 5478.8904221914545, 5515.972420137874, 5382.461753824587, 5391.366022506569, 5517.503424691797, 5535.446455354432, 5639.520640745583, 4161.729487583298, 4855.208255253173, 5015.498450155102, 5066.265391518283, 5425.647332923348], "value": 3978.012972289739, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.6505334699283, 679.4292794052994, 695.2352412346418, 756.5498528375615, 776.6854423958299, 14073.788893166598, 14539.0, 14592.0, 14692.0, 15476.458323958665, 14832.851671483282, 14961.0, 14996.250187490608, 14998.775018374734, 15014.399424023026, 806.4677312973439, 829.5769157729571, 831.9916800831975, 832.2967092806568, 832.6544484039147, 652.0903339840943, 670.4368330602293, 671.3253180553537, 804.3926835239386, 824.2458150792048, 863.2619110660389, 887.2458410351214, 889.0664801957951, 890.9064548222441, 898.1018981018981, 861.0356400831047, 862.9913700862974, 863.2360361080457, 865.7489328094845, 865.7619154732436, 510.20917577754335, 522.9111051121281, 620.2340109964191, 635.6218050260134, 637.4645297949698, 12395.876041239562, 14069.788953165711, 14341.924355673262, 14604.926975365055, 14612.561623151323, 13350.065495415325, 13601.659958501017, 13675.658108547266, 13786.241756703377, 13789.310534473336, 12163.51345946161, 12434.0, 12453.75092498145, 12508.687282817911, 12842.357882105858, 4355.651547876162, 4385.758783266919, 4450.443694538183, 4451.775761665535, 4535.931961020588, 92498.0, 94061.0, 95947.04052959451, 103313.0, 103464.48267758613, 10440.42577658221, 10693.78612427747, 10791.514381852836, 11033.779324413466, 11314.717132071599, 19592.314269000515, 20770.961451927342, 20800.375988720363, 20943.8952805235, 20948.89525552393, 7870.803229919184, 7895.447318687751, 7955.880661790078, 7976.361891048702, 8201.384896132695, 4143.668506519532, 4212.241796476603, 4304.332828411601, 4420.292753159479, 4515.616172625364, 3353.9329213415594, 3476.5480487536456, 3484.9477257841154, 3488.930221395558, 3489.6335884732243, 642.9260635026939, 646.8027251688186, 653.8169312592481, 677.2313424263497, 677.3734295776387, 585.5842351930092, 634.708034304216, 651.1242379000237, 652.54321974617, 654.0385633119402, 8695.260902823331, 9077.319201059947, 9080.727578172666, 9106.590203440861, 9107.22588579965, 562.443181250559, 583.9094940284263, 602.4126476685284, 612.3600837125215, 642.2678146912524, 3982.8406863725127, 4228.5982831630945, 4678.0, 4702.905941881143, 4784.210605250164, 74532.62733686405, 79737.0, 84814.30084903914, 110481.0, 110870.0, 76846.61576692192, 87505.12494875098, 90795.0, 95461.0453895466, 96168.55747163798, 81858.95352616113, 82793.0, 83781.16218837733, 85363.0, 85933.14066859377, 8109.878351824609, 8519.0, 9510.47692376926, 9514.953355130792, 9534.761630959281, 41539.58460415357, 46902.53097468981, 52971.20543191932, 54581.0, 55537.722311388185, 51852.481475184766, 52489.21266180934, 61911.38088619236, 70813.64593177207, 72691.0, 11763.294202347886, 11911.344876031555, 12108.636740897788, 12207.0, 12578.55975040869, 13879.444822206999, 14020.579382618538, 14510.85489145095, 14513.201773902536, 15218.315175817337, 12375.071869609996, 13382.397792099573, 13477.39351729155, 13921.860781392057, 13982.720345592828, 11723.0, 11909.702257443632, 12254.080943929062, 12280.32458214771, 13146.474141034634, 12013.0, 12815.161712282928, 12830.67923301925, 12840.97272218239, 12905.483780648668, 10909.836352454879, 11150.219484635998, 11236.55053797839, 11313.20807543464, 11616.0, 11189.160812939224, 11301.43492825339, 11485.966263036367, 11488.597899073493, 11491.770164596828], "value": 7279.266591572749, "unit": "score"}], "name": "dromaeo_css", "value": 6958.4648651382895, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 115.0, 114.0, 113.0, 115.0, 113.0, 113.0, 118.0, 160.0], "value": 119.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 110.0, 110.0, 144.0, 109.0, 110.0, 109.0, 109.0, 143.0, 108.0], "value": 122.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 185.0, 181.0, 183.0, 184.0, 181.0, 183.0, 183.0, 184.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 62.0, 78.0, 112.0, 79.0, 49.0, 73.0, 74.0, 110.0, 82.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 95.0, 71.0, 68.0, 69.0, 90.0, 69.0, 69.0, 93.0], "value": 78.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 128.0, 88.0, 90.0, 86.0, 91.0, 88.0, 90.0, 86.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 82.0, 80.0, 82.0, 81.0, 85.0, 85.0, 83.0, 83.0], "value": 82.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 55.0, 54.0, 56.0, 58.0, 58.0, 58.0, 58.0, 58.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 73.0, 75.0, 76.0, 72.0, 71.0, 76.0, 72.0, 73.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 101.0, 100.0, 76.0, 84.0, 84.0, 93.0, 106.0, 84.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 142.0, 141.0, 141.0, 144.0, 144.0, 144.0, 185.0, 151.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 171.0, 166.0, 165.0, 168.0, 164.0, 166.0, 165.0, 165.0], "value": 165.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 85.0, 86.0, 83.0, 70.0, 67.0, 66.0, 67.0, 66.0, 67.0], "value": 72.4, "unit": "score"}], "name": "kraken", "value": 1457.5000000000002, "alertThreshold": 2.0}]} 10:30:58 INFO - Return code: 0 10:30:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:30:58 INFO - # TBPL SUCCESS # 10:30:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:30:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:30:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:30:58 INFO - 2016-12-16 10:30:58.499 screenresolution[1925:16225] starting screenresolution argv=screenresolution get 10:30:58 INFO - 2016-12-16 10:30:58.503 screenresolution[1925:16225] Display 0: 1600x1200x32@60 10:30:58 INFO - 2016-12-16 10:30:58.509 screenresolution[1926:16228] starting screenresolution argv=screenresolution list 10:30:58 INFO - Available Modes on Display 0 10:30:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:30:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:30:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:30:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:30:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:30:58 INFO - Intel Iris: 10:30:58 INFO - Chipset Model: Intel Iris 10:30:58 INFO - Type: GPU 10:30:58 INFO - Bus: Built-In 10:30:58 INFO - VRAM (Dynamic, Max): 1536 MB 10:30:58 INFO - Vendor: Intel (0x8086) 10:30:58 INFO - Device ID: 0x0a2e 10:30:58 INFO - Revision ID: 0x0009 10:30:58 INFO - Displays: 10:30:58 INFO - Display: 10:30:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:30:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:30:58 INFO - Display Serial Number: 1600x1200 60 10:30:58 INFO - Main Display: Yes 10:30:58 INFO - Mirror: Off 10:30:58 INFO - Online: Yes 10:30:58 INFO - Rotation: Supported 10:30:58 INFO - Return code: 0 10:30:58 INFO - Running post-action listener: _resource_record_post_action 10:30:58 INFO - [mozharness: 2016-12-16 18:30:58.753439Z] Finished run-tests step (success) 10:30:58 INFO - Running post-run listener: _resource_record_post_run 10:30:58 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 864256; Write bytes: 652999680; Read time: 13; Write time: 2082 10:30:58 INFO - TinderboxPrint: CPU usage
53.4% 10:30:58 INFO - TinderboxPrint: I/O read bytes / time
864,256 / 13 10:30:58 INFO - TinderboxPrint: I/O write bytes / time
652,999,680 / 2,082 10:30:58 INFO - TinderboxPrint: CPU idle
2,259.4 (46.6%) 10:30:58 INFO - TinderboxPrint: CPU system
971.3 (20.0%) 10:30:58 INFO - TinderboxPrint: CPU user
1,619.0 (33.4%) 10:30:58 INFO - TinderboxPrint: Swap in / out
349,712,384 / 0 10:30:58 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344052224; Write bytes: 345536000; Read time: 26842; Write time: 835 10:30:58 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 864256; Write bytes: 300598784; Read time: 13; Write time: 1219 10:30:59 INFO - Running post-run listener: _upload_blobber_files 10:30:59 INFO - Blob upload gear active. 10:30:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:30:59 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:30:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:30:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:30:59 INFO - (blobuploader) - INFO - Open directory for files ... 10:30:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:30:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:30:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:31:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:31:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:31:00 INFO - (blobuploader) - INFO - Done attempting. 10:31:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:31:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:31:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:31:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:31:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:31:02 INFO - (blobuploader) - INFO - Done attempting. 10:31:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:31:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:31:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:31:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:31:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:31:04 INFO - (blobuploader) - INFO - Done attempting. 10:31:04 INFO - (blobuploader) - INFO - Iteration through files over. 10:31:04 INFO - Return code: 0 10:31:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:31:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:31:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da163a51cceefc20295174ebabbf725e38e0def4621aade797c71bf64b2f681d82879d49e6b0df541ada936bc8219dcba92cb82845facb97d414c94b83a8053b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/935c22e3650101fe4e92ba94879344cd98f56c1859f1ed9d507340a0b95ba1ed32eb2d234bfa574067ee5802adff9f6f0c6aa12739e09585202a0fe2b51700a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a953e7b15210832ac46c92c7310d1fb0ca08460e9dbd14253aa34535a9032efa8d8b53ee2bf295346e6b987462a5002de2b116835ac8562cf28b015de5a2ca8"} 10:31:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:31:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:31:04 INFO - Contents: 10:31:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da163a51cceefc20295174ebabbf725e38e0def4621aade797c71bf64b2f681d82879d49e6b0df541ada936bc8219dcba92cb82845facb97d414c94b83a8053b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/935c22e3650101fe4e92ba94879344cd98f56c1859f1ed9d507340a0b95ba1ed32eb2d234bfa574067ee5802adff9f6f0c6aa12739e09585202a0fe2b51700a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a953e7b15210832ac46c92c7310d1fb0ca08460e9dbd14253aa34535a9032efa8d8b53ee2bf295346e6b987462a5002de2b116835ac8562cf28b015de5a2ca8"} 10:31:04 INFO - Running post-run listener: copy_logs_to_upload_dir 10:31:04 INFO - Copying logs to upload dir... 10:31:04 INFO - mkdir: /builds/slave/test/build/upload/logs 10:31:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.913290 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-16 10:31:04.647361) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 10:31:04.651279) ========= 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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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/da163a51cceefc20295174ebabbf725e38e0def4621aade797c71bf64b2f681d82879d49e6b0df541ada936bc8219dcba92cb82845facb97d414c94b83a8053b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/935c22e3650101fe4e92ba94879344cd98f56c1859f1ed9d507340a0b95ba1ed32eb2d234bfa574067ee5802adff9f6f0c6aa12739e09585202a0fe2b51700a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a953e7b15210832ac46c92c7310d1fb0ca08460e9dbd14253aa34535a9032efa8d8b53ee2bf295346e6b987462a5002de2b116835ac8562cf28b015de5a2ca8"} build_url:https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011154 build_url: 'https://queue.taskcluster.net/v1/task/H5tFRQdBTTCZsAorIO2FLw/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/da163a51cceefc20295174ebabbf725e38e0def4621aade797c71bf64b2f681d82879d49e6b0df541ada936bc8219dcba92cb82845facb97d414c94b83a8053b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/935c22e3650101fe4e92ba94879344cd98f56c1859f1ed9d507340a0b95ba1ed32eb2d234bfa574067ee5802adff9f6f0c6aa12739e09585202a0fe2b51700a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a953e7b15210832ac46c92c7310d1fb0ca08460e9dbd14253aa34535a9032efa8d8b53ee2bf295346e6b987462a5002de2b116835ac8562cf28b015de5a2ca8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 10:31:04.684702) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 10:31:04.685021) ========= 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.eTTZVsgyrN/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.GoCe6yuaEC/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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.005277 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 10:31:04.733477) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 10:31:04.733777) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 10:31:04.734085) ========= ========= Total master_lag: 0.18 =========