builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0037 starttime: 1481808167.74 results: success (0) buildid: 20161215030701 builduid: 8cf536eb5a134210bec7145866753b17 revision: ea07499d13936886f4b10455628a2f951798bf9c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 05:22:47.739154) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 05:22:47.739528) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 05:22:47.739974) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 05:22:47.791475) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 05:22:47.791751) ========= 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.IvuR13GsjK/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.VikbbSClrF/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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-15 05:22:47-- 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% 14.6M=0.001s 2016-12-15 05:22:48 (14.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.546806 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 05:22:48.372485) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 05:22:48.372761) ========= 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.IvuR13GsjK/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.VikbbSClrF/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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.026589 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 05:22:48.422640) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 05:22:48.422956) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ea07499d13936886f4b10455628a2f951798bf9c --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 ea07499d13936886f4b10455628a2f951798bf9c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IvuR13GsjK/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.VikbbSClrF/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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-15 05:22:48,518 truncating revision to first 12 chars 2016-12-15 05:22:48,518 Setting DEBUG logging. 2016-12-15 05:22:48,518 attempt 1/10 2016-12-15 05:22:48,518 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ea07499d1393?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-15 05:22:49,190 unpacking tar archive at: mozilla-aurora-ea07499d1393/testing/mozharness/ program finished with exit code 0 elapsedTime=0.924771 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 05:22:49.370953) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 05:22:49.371270) ========= script_repo_revision: ea07499d13936886f4b10455628a2f951798bf9c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 05:22:49.371661) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 05:22:49.371927) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 05:22:49.394242) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-15 05:22:49.394645) ========= /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.IvuR13GsjK/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.VikbbSClrF/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 05:22:49 INFO - MultiFileLogger online at 20161215 05:22:49 in /builds/slave/test 05:22:49 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 05:22:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:22:49 INFO - {'append_to_log': False, 05:22:49 INFO - 'base_work_dir': '/builds/slave/test', 05:22:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:22:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:22:49 INFO - 'branch': 'Mozilla-Aurora', 05:22:49 INFO - 'buildbot_json_path': 'buildprops.json', 05:22:49 INFO - 'config_files': ('talos/mac_config.py',), 05:22:49 INFO - 'default_actions': ('clobber', 05:22:49 INFO - 'read-buildbot-config', 05:22:49 INFO - 'download-and-extract', 05:22:49 INFO - 'populate-webroot', 05:22:49 INFO - 'create-virtualenv', 05:22:49 INFO - 'install', 05:22:49 INFO - 'run-tests'), 05:22:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:22:49 INFO - 'download_minidump_stackwalk': True, 05:22:49 INFO - 'download_symbols': 'ondemand', 05:22:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:22:49 INFO - 'tooltool.py': '/tools/tooltool.py', 05:22:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:22:49 INFO - '/tools/misc-python/virtualenv.py')}, 05:22:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:22:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:22:49 INFO - 'installer_path': 'installer.exe', 05:22:49 INFO - 'log_level': 'info', 05:22:49 INFO - 'log_name': 'talos', 05:22:49 INFO - 'log_to_console': True, 05:22:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:22:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:22:49 INFO - 'opt_config_files': (), 05:22:49 INFO - 'pip_index': False, 05:22:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:22:49 INFO - 'cmd': ('bash', 05:22:49 INFO - '-c', 05:22:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:22:49 INFO - 'enabled': True, 05:22:49 INFO - 'halt_on_failure': False, 05:22:49 INFO - 'name': 'check_screen_resolution'},), 05:22:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:22:49 INFO - 'cmd': ('bash', 05:22:49 INFO - '-c', 05:22:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:22:49 INFO - 'enabled': True, 05:22:49 INFO - 'halt_on_failure': False, 05:22:49 INFO - 'name': 'check_screen_resolution'},), 05:22:49 INFO - 'run_cmd_checks_enabled': True, 05:22:49 INFO - 'sps_profile': False, 05:22:49 INFO - 'sps_profile_interval': 0, 05:22:49 INFO - 'suite': 'dromaeojs', 05:22:49 INFO - 'system_bits': '32', 05:22:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:22:49 INFO - 'title': 't-yosemite-r7-0037', 05:22:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:22:49 INFO - 'use_talos_json': True, 05:22:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:22:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:22:49 INFO - 'work_dir': 'build'} 05:22:49 INFO - [mozharness: 2016-12-15 13:22:49.599161Z] Running clobber step. 05:22:49 INFO - Running pre-action listener: _resource_record_pre_action 05:22:49 INFO - Running main action method: clobber 05:22:49 INFO - rmtree: /builds/slave/test/build 05:22:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:22:51 INFO - Running post-action listener: _resource_record_post_action 05:22:51 INFO - [mozharness: 2016-12-15 13:22:51.945694Z] Finished clobber step (success) 05:22:51 INFO - [mozharness: 2016-12-15 13:22:51.945858Z] Running read-buildbot-config step. 05:22:51 INFO - Running pre-action listener: _resource_record_pre_action 05:22:51 INFO - Running main action method: read_buildbot_config 05:22:51 INFO - Using buildbot properties: 05:22:51 INFO - { 05:22:51 INFO - "project": "", 05:22:51 INFO - "product": "firefox", 05:22:51 INFO - "script_repo_revision": "production", 05:22:51 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 05:22:51 INFO - "repository": "", 05:22:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 05:22:51 INFO - "buildid": "20161215030701", 05:22:51 INFO - "pgo_build": "False", 05:22:51 INFO - "basedir": "/builds/slave/test", 05:22:51 INFO - "buildnumber": 9, 05:22:51 INFO - "slavename": "t-yosemite-r7-0037", 05:22:51 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:22:51 INFO - "platform": "yosemite_r7", 05:22:51 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:22:51 INFO - "branch": "mozilla-aurora", 05:22:51 INFO - "repo_path": "releases/mozilla-aurora", 05:22:51 INFO - "revision": "ea07499d13936886f4b10455628a2f951798bf9c", 05:22:51 INFO - "stage_platform": "macosx64", 05:22:51 INFO - "builduid": "8cf536eb5a134210bec7145866753b17", 05:22:51 INFO - "slavebuilddir": "test" 05:22:51 INFO - } 05:22:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 05:22:51 INFO - Running post-action listener: _resource_record_post_action 05:22:51 INFO - [mozharness: 2016-12-15 13:22:51.949024Z] Finished read-buildbot-config step (success) 05:22:51 INFO - [mozharness: 2016-12-15 13:22:51.949163Z] Running download-and-extract step. 05:22:51 INFO - Running pre-action listener: _resource_record_pre_action 05:22:51 INFO - Running main action method: download_and_extract 05:22:51 INFO - mkdir: /builds/slave/test/build/tests 05:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:51 INFO - trying https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 05:22:51 INFO - Downloading https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/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 05:22:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/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 05:22:53 INFO - Downloaded 1199 bytes. 05:22:53 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 05:22:53 INFO - Using the following test package requirements: 05:22:53 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 05:22:53 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:22:53 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 05:22:53 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:22:53 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 05:22:53 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:22:53 INFO - u'jsshell-mac.zip'], 05:22:53 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:22:53 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 05:22:53 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 05:22:53 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:22:53 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 05:22:53 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:22:53 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 05:22:53 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:22:53 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 05:22:53 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:22:53 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 05:22:53 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:22:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 05:22:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:22:53 INFO - Fetch https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 05:22:53 INFO - Http code: 200 05:22:53 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 05:22:53 INFO - x-amz-cf-id: Iup36j6s5VCdEXTZkiDXgpWjpGbFRPsZkIJASc9ELdQy0NZmUtJ6tA== 05:22:53 INFO - x-amz-version-id: F9LUQUVG.jIBNSNBWXYh_UuSTruKM34g 05:22:54 INFO - Expected file size: 16281004 05:22:54 INFO - Obtained file size: 16281004 05:23:00 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 05:23:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 05:23:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:23:00 INFO - Fetch https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 05:23:01 INFO - Http code: 200 05:23:01 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 05:23:01 INFO - x-amz-cf-id: WgQdakxD6M8xLRturpmBnRnQ-UZujdjEAkk7fqQJlZmKdU0BgWv5ZQ== 05:23:01 INFO - x-amz-version-id: F9LUQUVG.jIBNSNBWXYh_UuSTruKM34g 05:23:01 INFO - Expected file size: 16281004 05:23:01 INFO - Obtained file size: 16281004 05:23:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 05:23:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:23:08 INFO - Fetch https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 05:23:09 INFO - Http code: 200 05:23:09 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 05:23:09 INFO - x-amz-cf-id: TfZjkrFsqtLO5a6K9SCy7NH9rpxpIBcj9y7clFVUoERb49QgI4ajOQ== 05:23:09 INFO - x-amz-version-id: h2x0v8PmoIZNbJifzF7B6OIGGFohQbKr 05:23:09 INFO - Expected file size: 14409038 05:23:09 INFO - Obtained file size: 14409038 05:23:09 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')]} 05:23:09 INFO - trying https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:23:09 INFO - Downloading https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 05:23:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/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 05:23:12 INFO - Downloaded 109780677 bytes. 05:23:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:23:12 INFO - mkdir: /builds/slave/test/properties 05:23:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:23:12 INFO - Writing to file /builds/slave/test/properties/build_url 05:23:12 INFO - Contents: 05:23:12 INFO - build_url:https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:23:13 INFO - Running post-action listener: _resource_record_post_action 05:23:13 INFO - Running post-action listener: set_extra_try_arguments 05:23:13 INFO - [mozharness: 2016-12-15 13:23:13.646749Z] Finished download-and-extract step (success) 05:23:13 INFO - [mozharness: 2016-12-15 13:23:13.646909Z] Running populate-webroot step. 05:23:13 INFO - Running pre-action listener: _resource_record_pre_action 05:23:13 INFO - Running main action method: populate_webroot 05:23:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:23:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:23:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:23:13 INFO - '--tppagecycles', 05:23:13 INFO - '7'], 05:23:13 INFO - 'tests': ['tsvgm']}}, 05:23:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:23:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:23:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:23:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:23:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:23:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:23:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:13 INFO - 'talos_options': ['--disable-e10s'], 05:23:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:13 INFO - 'talos_options': ['--disable-e10s'], 05:23:13 INFO - 'tests': ['damp', 'tps']}, 05:23:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:13 INFO - 'tests': ['damp', 'tps']}, 05:23:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:23:13 INFO - 'tests': ['dromaeo_dom']}, 05:23:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:23:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:23:13 INFO - 'tests': ['basic_compositor_video']}, 05:23:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:23:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:23:13 INFO - 'tests': ['a11yr', 05:23:13 INFO - 'ts_paint', 05:23:13 INFO - 'tpaint', 05:23:13 INFO - 'sessionrestore', 05:23:13 INFO - 'sessionrestore_no_auto_restore', 05:23:13 INFO - 'tabpaint']}, 05:23:13 INFO - 'other-e10s': {'tests': ['a11yr', 05:23:13 INFO - 'ts_paint', 05:23:13 INFO - 'tpaint', 05:23:13 INFO - 'sessionrestore', 05:23:13 INFO - 'sessionrestore_no_auto_restore', 05:23:13 INFO - 'tabpaint']}, 05:23:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:23:13 INFO - 'ts_paint', 05:23:13 INFO - 'tpaint', 05:23:13 INFO - 'sessionrestore', 05:23:13 INFO - 'sessionrestore_no_auto_restore', 05:23:13 INFO - 'tabpaint']}, 05:23:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:23:13 INFO - 'ts_paint', 05:23:13 INFO - 'tpaint', 05:23:13 INFO - 'sessionrestore', 05:23:13 INFO - 'sessionrestore_no_auto_restore', 05:23:13 INFO - 'tabpaint']}, 05:23:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:23:13 INFO - 'tests': ['a11yr', 05:23:13 INFO - 'ts_paint', 05:23:13 INFO - 'tpaint', 05:23:13 INFO - 'sessionrestore', 05:23:13 INFO - 'sessionrestore_no_auto_restore', 05:23:13 INFO - 'tabpaint']}, 05:23:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:23:13 INFO - 'tests': ['a11yr', 05:23:13 INFO - 'ts_paint', 05:23:13 INFO - 'tpaint', 05:23:13 INFO - 'sessionrestore', 05:23:13 INFO - 'sessionrestore_no_auto_restore', 05:23:13 INFO - 'tabpaint']}, 05:23:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:23:13 INFO - 'tests': ['tsvgx', 05:23:13 INFO - 'tsvgr_opacity', 05:23:13 INFO - 'tart', 05:23:13 INFO - 'tscrollx', 05:23:13 INFO - 'cart']}, 05:23:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:23:13 INFO - 'tsvgr_opacity', 05:23:13 INFO - 'tart', 05:23:13 INFO - 'tscrollx', 05:23:13 INFO - 'cart']}, 05:23:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:13 INFO - 'talos_options': ['--disable-e10s'], 05:23:13 INFO - 'tests': ['tp5o']}, 05:23:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:13 INFO - 'tests': ['tp5o']}, 05:23:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:13 INFO - 'talos_options': ['--disable-e10s', 05:23:13 INFO - '--xperf_path', 05:23:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:13 INFO - 'tests': ['tp5n']}, 05:23:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:13 INFO - 'talos_options': ['--xperf_path', 05:23:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:13 INFO - 'tests': ['tp5n']}}, 05:23:13 INFO - 'talos.zip': {'path': '', 05:23:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:23:13 INFO - Running post-action listener: _resource_record_post_action 05:23:13 INFO - [mozharness: 2016-12-15 13:23:13.666007Z] Finished populate-webroot step (success) 05:23:13 INFO - [mozharness: 2016-12-15 13:23:13.666145Z] Running create-virtualenv step. 05:23:13 INFO - Running pre-action listener: _resource_record_pre_action 05:23:13 INFO - Running main action method: create_virtualenv 05:23:13 INFO - Creating virtualenv /builds/slave/test/build/venv 05:23: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 05:23:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:23:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:23:13 INFO - Using real prefix '/tools/python27' 05:23:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:23:14 INFO - Installing distribute.............................................................................................................................................................................................done. 05:23:17 INFO - Installing pip.................done. 05:23:17 INFO - Return code: 0 05:23:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:23:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:23:17 INFO - Reading from file tmpfile_stdout 05:23:17 INFO - Output received: 05:23: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) 05:23:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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 0x106b12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff769d80430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106926d30>, '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 0x10678cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10691eb20>, '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.IvuR13GsjK/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.VikbbSClrF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 05:23: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 05:23: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 05:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IvuR13GsjK/Render', 05:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:17 INFO - 'HOME': '/Users/cltbld', 05:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:17 INFO - 'LOGNAME': 'cltbld', 05:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:17 INFO - 'MOZ_NO_REMOTE': '1', 05:23:17 INFO - 'NO_EM_RESTART': '1', 05:23:17 INFO - 'PAGER': '/bin/cat', 05:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:17 INFO - 'PWD': '/builds/slave/test', 05:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:17 INFO - 'SHELL': '/bin/bash', 05:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VikbbSClrF/Listeners', 05:23:17 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 05:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:17 INFO - 'USER': 'cltbld', 05:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:17 INFO - 'XPC_FLAGS': '0x0', 05:23:17 INFO - 'XPC_SERVICE_NAME': '0', 05:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:17 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 05:23:17 INFO - Cleaning up... 05:23:17 INFO - Return code: 0 05:23:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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', '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 0x106b12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff769d80430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106926d30>, '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 0x10678cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10691eb20>, '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.IvuR13GsjK/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.VikbbSClrF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 05:23: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:23: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 psutil>=3.1.1 05:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IvuR13GsjK/Render', 05:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:17 INFO - 'HOME': '/Users/cltbld', 05:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:17 INFO - 'LOGNAME': 'cltbld', 05:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:17 INFO - 'MOZ_NO_REMOTE': '1', 05:23:17 INFO - 'NO_EM_RESTART': '1', 05:23:17 INFO - 'PAGER': '/bin/cat', 05:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:17 INFO - 'PWD': '/builds/slave/test', 05:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:17 INFO - 'SHELL': '/bin/bash', 05:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VikbbSClrF/Listeners', 05:23:17 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 05:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:17 INFO - 'USER': 'cltbld', 05:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:17 INFO - 'XPC_FLAGS': '0x0', 05:23:17 INFO - 'XPC_SERVICE_NAME': '0', 05:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:18 INFO - Downloading/unpacking psutil>=3.1.1 05:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:23:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:21 INFO - Installing collected packages: psutil 05:23:21 INFO - Running setup.py install for psutil 05:23:22 INFO - building 'psutil._psutil_osx' extension 05:23: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 05:23: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 05:23: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 05:23: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 05:23:22 INFO - building 'psutil._psutil_posix' extension 05:23: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 05:23:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:23:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:23:22 INFO - ^ 05:23:22 INFO - 1 warning generated. 05:23: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 05:23:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:23 INFO - Successfully installed psutil 05:23:23 INFO - Cleaning up... 05:23:23 INFO - Return code: 0 05:23:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff769d80430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106926d30>, '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 0x10678cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10691eb20>, '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.IvuR13GsjK/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.VikbbSClrF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 05:23:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:23:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:23:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IvuR13GsjK/Render', 05:23:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:23 INFO - 'HOME': '/Users/cltbld', 05:23:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:23 INFO - 'LOGNAME': 'cltbld', 05:23:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:23 INFO - 'MOZ_NO_REMOTE': '1', 05:23:23 INFO - 'NO_EM_RESTART': '1', 05:23:23 INFO - 'PAGER': '/bin/cat', 05:23:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:23 INFO - 'PWD': '/builds/slave/test', 05:23:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:23 INFO - 'SHELL': '/bin/bash', 05:23:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VikbbSClrF/Listeners', 05:23:23 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 05:23:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:23 INFO - 'USER': 'cltbld', 05:23:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:23 INFO - 'XPC_FLAGS': '0x0', 05:23:23 INFO - 'XPC_SERVICE_NAME': '0', 05:23:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:23:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:23:26 INFO - Installing collected packages: mozsystemmonitor 05:23:26 INFO - Running setup.py install for mozsystemmonitor 05:23:26 INFO - Successfully installed mozsystemmonitor 05:23:26 INFO - Cleaning up... 05:23:26 INFO - Return code: 0 05:23:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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 0x106b12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff769d80430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106926d30>, '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 0x10678cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10691eb20>, '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.IvuR13GsjK/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.VikbbSClrF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 05:23: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 05:23: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 05:23:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IvuR13GsjK/Render', 05:23:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:26 INFO - 'HOME': '/Users/cltbld', 05:23:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:26 INFO - 'LOGNAME': 'cltbld', 05:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:26 INFO - 'MOZ_NO_REMOTE': '1', 05:23:26 INFO - 'NO_EM_RESTART': '1', 05:23:26 INFO - 'PAGER': '/bin/cat', 05:23:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:26 INFO - 'PWD': '/builds/slave/test', 05:23:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:26 INFO - 'SHELL': '/bin/bash', 05:23:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VikbbSClrF/Listeners', 05:23:26 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 05:23:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:26 INFO - 'USER': 'cltbld', 05:23:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:26 INFO - 'XPC_FLAGS': '0x0', 05:23:26 INFO - 'XPC_SERVICE_NAME': '0', 05:23:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:26 INFO - Downloading/unpacking jsonschema==2.5.1 05:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:23:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:23:29 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 05:23:29 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 05:23:29 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 05:23:29 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 05:23:29 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 05:23:29 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 05:23:29 INFO - Downloading functools32-3.2.3-2.tar.gz 05:23:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:23:29 INFO - warning: no files found matching '*.txt' 05:23:29 INFO - no previously-included directories found matching 'build' 05:23:29 INFO - no previously-included directories found matching 'dist' 05:23:29 INFO - no previously-included directories found matching '.git*' 05:23:29 INFO - Installing collected packages: jsonschema, functools32 05:23:29 INFO - Running setup.py install for functools32 05:23:29 INFO - warning: no files found matching '*.txt' 05:23:29 INFO - no previously-included directories found matching 'build' 05:23:29 INFO - no previously-included directories found matching 'dist' 05:23:29 INFO - no previously-included directories found matching '.git*' 05:23:29 INFO - Successfully installed jsonschema functools32 05:23:29 INFO - Cleaning up... 05:23:29 INFO - Return code: 0 05:23:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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 0x106b12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff769d80430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106926d30>, '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 0x10678cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10691eb20>, '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.IvuR13GsjK/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.VikbbSClrF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 05:23: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 05:23: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 05:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IvuR13GsjK/Render', 05:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:29 INFO - 'HOME': '/Users/cltbld', 05:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:29 INFO - 'LOGNAME': 'cltbld', 05:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:29 INFO - 'MOZ_NO_REMOTE': '1', 05:23:29 INFO - 'NO_EM_RESTART': '1', 05:23:29 INFO - 'PAGER': '/bin/cat', 05:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:29 INFO - 'PWD': '/builds/slave/test', 05:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:29 INFO - 'SHELL': '/bin/bash', 05:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VikbbSClrF/Listeners', 05:23:29 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 05:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:29 INFO - 'USER': 'cltbld', 05:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:29 INFO - 'XPC_FLAGS': '0x0', 05:23:29 INFO - 'XPC_SERVICE_NAME': '0', 05:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:23:30 INFO - Cleaning up... 05:23:30 INFO - Return code: 0 05:23:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106b12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff769d80430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106926d30>, '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 0x10678cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10691eb20>, '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.IvuR13GsjK/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.VikbbSClrF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 05:23:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IvuR13GsjK/Render', 05:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:30 INFO - 'HOME': '/Users/cltbld', 05:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:30 INFO - 'LOGNAME': 'cltbld', 05:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:30 INFO - 'MOZ_NO_REMOTE': '1', 05:23:30 INFO - 'NO_EM_RESTART': '1', 05:23:30 INFO - 'PAGER': '/bin/cat', 05:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:30 INFO - 'PWD': '/builds/slave/test', 05:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:30 INFO - 'SHELL': '/bin/bash', 05:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VikbbSClrF/Listeners', 05:23:30 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 05:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:30 INFO - 'USER': 'cltbld', 05:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:30 INFO - 'XPC_FLAGS': '0x0', 05:23:30 INFO - 'XPC_SERVICE_NAME': '0', 05:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:30 INFO - Downloading/unpacking blobuploader==1.2.4 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23:33 INFO - Downloading blobuploader-1.2.4.tar.gz 05:23:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:23:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:23:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23:34 INFO - Downloading docopt-0.6.1.tar.gz 05:23:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:23:34 INFO - Installing collected packages: blobuploader, requests, docopt 05:23:34 INFO - Running setup.py install for blobuploader 05:23:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:23:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:23:34 INFO - Running setup.py install for requests 05:23:34 INFO - Running setup.py install for docopt 05:23:35 INFO - Successfully installed blobuploader requests docopt 05:23:35 INFO - Cleaning up... 05:23:35 INFO - Return code: 0 05:23:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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 0x106b12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff769d80430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106926d30>, '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 0x10678cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10691eb20>, '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.IvuR13GsjK/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.VikbbSClrF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 05:23: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 05:23: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 05:23:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IvuR13GsjK/Render', 05:23:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:35 INFO - 'HOME': '/Users/cltbld', 05:23:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:35 INFO - 'LOGNAME': 'cltbld', 05:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:35 INFO - 'MOZ_NO_REMOTE': '1', 05:23:35 INFO - 'NO_EM_RESTART': '1', 05:23:35 INFO - 'PAGER': '/bin/cat', 05:23:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:35 INFO - 'PWD': '/builds/slave/test', 05:23:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:35 INFO - 'SHELL': '/bin/bash', 05:23:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VikbbSClrF/Listeners', 05:23:35 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 05:23:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:35 INFO - 'USER': 'cltbld', 05:23:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:35 INFO - 'XPC_FLAGS': '0x0', 05:23:35 INFO - 'XPC_SERVICE_NAME': '0', 05:23:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:35 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-N9BLaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:35 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-uNLijN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:35 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-0lNl0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:35 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-YgZjnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:35 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-P6rvzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:36 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-rdvE9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:36 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-HD9_N0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:36 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-BKBhnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:36 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-gFw0un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:36 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-NiK6mq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:36 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-8ASLeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:36 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-s4hJRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:36 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-v5p8zL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:36 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-hDdv5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:36 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-WzKUzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:37 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-afaNve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:37 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-iQQwC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:23:37 INFO - Running setup.py install for manifestparser 05:23:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:23:37 INFO - Running setup.py install for mozcrash 05:23:37 INFO - Running setup.py install for mozdebug 05:23:37 INFO - Running setup.py install for mozdevice 05:23:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:23:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:23:37 INFO - Running setup.py install for mozfile 05:23:38 INFO - Running setup.py install for mozhttpd 05:23:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:23:38 INFO - Running setup.py install for mozinfo 05:23:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:23:38 INFO - Running setup.py install for mozInstall 05:23:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:23:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:23:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:23:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:23:38 INFO - Running setup.py install for mozleak 05:23:38 INFO - Running setup.py install for mozlog 05:23:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:23:38 INFO - Running setup.py install for moznetwork 05:23:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:23:38 INFO - Running setup.py install for mozprocess 05:23:39 INFO - Running setup.py install for mozprofile 05:23:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:23:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:23:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:23:39 INFO - Running setup.py install for mozrunner 05:23:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:23:39 INFO - Running setup.py install for mozscreenshot 05:23:39 INFO - Running setup.py install for moztest 05:23:39 INFO - Running setup.py install for mozversion 05:23:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:23:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:23:39 INFO - Cleaning up... 05:23:39 INFO - Return code: 0 05:23:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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 0x106b12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff769d80430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106926d30>, '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 0x10678cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10691eb20>, '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.IvuR13GsjK/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.VikbbSClrF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 05:23: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 05:23: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 05:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IvuR13GsjK/Render', 05:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:39 INFO - 'HOME': '/Users/cltbld', 05:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:39 INFO - 'LOGNAME': 'cltbld', 05:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:39 INFO - 'MOZ_NO_REMOTE': '1', 05:23:39 INFO - 'NO_EM_RESTART': '1', 05:23:39 INFO - 'PAGER': '/bin/cat', 05:23:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:39 INFO - 'PWD': '/builds/slave/test', 05:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:39 INFO - 'SHELL': '/bin/bash', 05:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VikbbSClrF/Listeners', 05:23:39 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 05:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:39 INFO - 'USER': 'cltbld', 05:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:39 INFO - 'XPC_FLAGS': '0x0', 05:23:39 INFO - 'XPC_SERVICE_NAME': '0', 05:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:40 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-sg8W85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23: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)) 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:40 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-CSjlwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23: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)) 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:40 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-v1c6vV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23: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)) 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:40 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-ggUl00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23: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)) 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:40 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-HjU_rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23: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)) 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:40 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-tprFGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23: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)) 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:40 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-bKu5RK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23: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)) 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:40 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-nSHm2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23: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)) 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:40 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-4ueULr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:41 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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:41 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-XRsz30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23: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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:41 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-SGeuZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23: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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:41 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-A2UOzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23: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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:41 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-UO27GL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23: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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:41 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-mdVeYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23: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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:41 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-2E01lU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23: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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:41 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-1L8yqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23: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)) 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:41 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-VqwIvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23: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)) 05:23: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)) 05:23: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)) 05:23: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)) 05:23: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)) 05:23: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)) 05:23:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23:44 INFO - Downloading blessings-1.6.tar.gz 05:23:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:23:44 INFO - Installing collected packages: blessings 05:23:44 INFO - Running setup.py install for blessings 05:23:44 INFO - Successfully installed blessings 05:23:44 INFO - Cleaning up... 05:23:44 INFO - Return code: 0 05:23:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:23:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:45 INFO - Reading from file tmpfile_stdout 05:23:45 INFO - Current package versions: 05:23:45 INFO - blessings == 1.6 05:23:45 INFO - blobuploader == 1.2.4 05:23:45 INFO - docopt == 0.6.1 05:23:45 INFO - functools32 == 3.2.3-2 05:23:45 INFO - jsonschema == 2.5.1 05:23:45 INFO - manifestparser == 1.1 05:23:45 INFO - mozInstall == 1.12 05:23:45 INFO - mozcrash == 1.0 05:23:45 INFO - mozdebug == 0.1 05:23:45 INFO - mozdevice == 0.48 05:23:45 INFO - mozfile == 1.2 05:23:45 INFO - mozhttpd == 0.7 05:23:45 INFO - mozinfo == 0.9 05:23:45 INFO - mozleak == 0.1 05:23:45 INFO - mozlog == 3.4 05:23:45 INFO - moznetwork == 0.27 05:23:45 INFO - mozprocess == 0.23 05:23:45 INFO - mozprofile == 0.28 05:23:45 INFO - mozrunner == 6.13 05:23:45 INFO - mozscreenshot == 0.1 05:23:45 INFO - mozsystemmonitor == 0.3 05:23:45 INFO - moztest == 0.7 05:23:45 INFO - mozversion == 1.4 05:23:45 INFO - psutil == 3.1.1 05:23:45 INFO - requests == 1.2.3 05:23:45 INFO - wsgiref == 0.1.2 05:23:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:45 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 0x106b12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff769d80430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106926d30>, '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 0x10678cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10691eb20>, '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.IvuR13GsjK/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.VikbbSClrF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 05:23:45 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 05:23:45 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 05:23:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IvuR13GsjK/Render', 05:23:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:45 INFO - 'HOME': '/Users/cltbld', 05:23:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:45 INFO - 'LOGNAME': 'cltbld', 05:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:45 INFO - 'MOZ_NO_REMOTE': '1', 05:23:45 INFO - 'NO_EM_RESTART': '1', 05:23:45 INFO - 'PAGER': '/bin/cat', 05:23:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:45 INFO - 'PWD': '/builds/slave/test', 05:23:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:45 INFO - 'SHELL': '/bin/bash', 05:23:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VikbbSClrF/Listeners', 05:23:45 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 05:23:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:45 INFO - 'USER': 'cltbld', 05:23:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:45 INFO - 'XPC_FLAGS': '0x0', 05:23:45 INFO - 'XPC_SERVICE_NAME': '0', 05:23:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23: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)) 05:23: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)) 05:23: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)) 05:23: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)) 05:23: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)) 05:23: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)) 05:23: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)) 05:23: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)) 05:23: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)) 05:23: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)) 05:23: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)) 05:23:45 INFO - Cleaning up... 05:23:45 INFO - Return code: 0 05:23:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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 0x106b12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff769d80430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106926d30>, '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 0x10678cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10691eb20>, '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.IvuR13GsjK/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.VikbbSClrF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 05:23: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 05:23: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 05:23:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IvuR13GsjK/Render', 05:23:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:45 INFO - 'HOME': '/Users/cltbld', 05:23:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:45 INFO - 'LOGNAME': 'cltbld', 05:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:45 INFO - 'MOZ_NO_REMOTE': '1', 05:23:45 INFO - 'NO_EM_RESTART': '1', 05:23:45 INFO - 'PAGER': '/bin/cat', 05:23:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:45 INFO - 'PWD': '/builds/slave/test', 05:23:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:45 INFO - 'SHELL': '/bin/bash', 05:23:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VikbbSClrF/Listeners', 05:23:45 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 05:23:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:45 INFO - 'USER': 'cltbld', 05:23:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:45 INFO - 'XPC_FLAGS': '0x0', 05:23:45 INFO - 'XPC_SERVICE_NAME': '0', 05:23:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:23:45 INFO - Cleaning up... 05:23:45 INFO - Return code: 0 05:23:45 INFO - Running post-action listener: _resource_record_post_action 05:23:45 INFO - Running post-action listener: _start_resource_monitoring 05:23:45 INFO - Starting resource monitoring. 05:23:45 INFO - [mozharness: 2016-12-15 13:23:45.705409Z] Finished create-virtualenv step (success) 05:23:45 INFO - [mozharness: 2016-12-15 13:23:45.706374Z] Running install step. 05:23:45 INFO - Running pre-action listener: _resource_record_pre_action 05:23:45 INFO - Running main action method: install 05:23:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:45 INFO - Reading from file tmpfile_stdout 05:23:45 INFO - Detecting whether we're running mozinstall >=1.0... 05:23:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:23:46 INFO - Reading from file tmpfile_stdout 05:23:46 INFO - Output received: 05:23:46 INFO - Usage: mozinstall [options] installer 05:23:46 INFO - Options: 05:23:46 INFO - -h, --help show this help message and exit 05:23:46 INFO - -d DEST, --destination=DEST 05:23:46 INFO - Directory to install application into. [default: 05:23:46 INFO - "/builds/slave/test"] 05:23:46 INFO - --app=APP Application being installed. [default: firefox] 05:23:46 INFO - mkdir: /builds/slave/test/build/application 05:23:46 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'] 05:23:46 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 05:24:16 INFO - Reading from file tmpfile_stdout 05:24:16 INFO - Output received: 05:24:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:24:16 INFO - Running post-action listener: _resource_record_post_action 05:24:16 INFO - [mozharness: 2016-12-15 13:24:16.871217Z] Finished install step (success) 05:24:16 INFO - [mozharness: 2016-12-15 13:24:16.871352Z] Running run-tests step. 05:24:16 INFO - Running pre-action listener: _resource_record_pre_action 05:24:16 INFO - Running main action method: run_tests 05:24:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:24:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:24:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:24:16 INFO - 2016-12-15 05:24:16.885 screenresolution[1633:7675] starting screenresolution argv=screenresolution get 05:24:16 INFO - 2016-12-15 05:24:16.888 screenresolution[1633:7675] Display 0: 1600x1200x32@60 05:24:16 INFO - 2016-12-15 05:24:16.894 screenresolution[1634:7677] starting screenresolution argv=screenresolution list 05:24:16 INFO - Available Modes on Display 0 05:24:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:24:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:24:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:24:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:24:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:24:17 INFO - Intel Iris: 05:24:17 INFO - Chipset Model: Intel Iris 05:24:17 INFO - Type: GPU 05:24:17 INFO - Bus: Built-In 05:24:17 INFO - VRAM (Dynamic, Max): 1536 MB 05:24:17 INFO - Vendor: Intel (0x8086) 05:24:17 INFO - Device ID: 0x0a2e 05:24:17 INFO - Revision ID: 0x0009 05:24:17 INFO - Displays: 05:24:17 INFO - Display: 05:24:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:24:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:24:17 INFO - Display Serial Number: 1600x1200 60 05:24:17 INFO - Main Display: Yes 05:24:17 INFO - Mirror: Off 05:24:17 INFO - Online: Yes 05:24:17 INFO - Rotation: Supported 05:24:17 INFO - Return code: 0 05:24:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:24:17 INFO - Python 2.7.3 05:24:17 INFO - Return code: 0 05:24:17 INFO - grabbing minidump binary from tooltool 05:24: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')]} 05:24: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 0x106926d30>, '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 0x10678cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10691eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:24: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 05:24: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 05:24: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 05:24:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:24:17 INFO - Return code: 0 05:24:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:24:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:24:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:24:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:24:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:24: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/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0037', '--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 05:24: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/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0037 --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 05:24:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IvuR13GsjK/Render', 05:24:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:17 INFO - 'HOME': '/Users/cltbld', 05:24:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:17 INFO - 'LOGNAME': 'cltbld', 05:24:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:24:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:17 INFO - 'MOZ_NO_REMOTE': '1', 05:24:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:24:17 INFO - 'NO_EM_RESTART': '1', 05:24:17 INFO - 'PAGER': '/bin/cat', 05:24:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:17 INFO - 'PWD': '/builds/slave/test', 05:24:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:24:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:17 INFO - 'SHELL': '/bin/bash', 05:24:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VikbbSClrF/Listeners', 05:24:17 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 05:24:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:17 INFO - 'USER': 'cltbld', 05:24:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:17 INFO - 'XPC_FLAGS': '0x0', 05:24:17 INFO - 'XPC_SERVICE_NAME': '0', 05:24:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24: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/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0037', '--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 05:24:17 INFO - mozversion application_buildid: 20161215030701 05:24:17 INFO - mozversion application_changeset: ea07499d13936886f4b10455628a2f951798bf9c 05:24:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:24:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:24:17 INFO - mozversion application_name: Firefox 05:24:17 INFO - mozversion application_remotingname: firefox-dev 05:24:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:24:17 INFO - mozversion application_vendor: Mozilla 05:24:17 INFO - mozversion application_version: 52.0a2 05:24:17 INFO - mozversion platform_buildid: 20161215030701 05:24:17 INFO - mozversion platform_changeset: ea07499d13936886f4b10455628a2f951798bf9c 05:24:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:24:17 INFO - mozversion platform_version: 52.0a2 05:24:17 INFO - using testdate: 1481808257 05:24:17 INFO - actual date: 1481808257 05:24:17 INFO - starting webserver on 'localhost:49236' 05:24:17 INFO - SUITE-START | Running 2 tests 05:24:17 INFO - TEST-START | dromaeo_css 05:24:17 INFO - operating with platform_type : mac_ 05:24:17 INFO - Initialising browser for dromaeo_css test... 05:24:17 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmp_pki4R/profile http://localhost:49236/getInfo.html) 05:24:20 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 05:24:20 INFO - PROCESS | 1644 | colorDepth:24 05:24:20 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 05:24:20 INFO - PROCESS | 1644 | __metrics 05:24:20 INFO - PROCESS | 1644 | 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" 05:24:20 INFO - PROCESS | 1644 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:24:21 INFO - TEST-INFO | 1644: exit 0 05:24:21 INFO - Browser initialized. 05:24:21 INFO - Running cycle 1/1 for dromaeo_css test... 05:24:21 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmp_pki4R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:33 INFO - PROCESS | 1648 | RSS: Main: 223002624 05:24:33 INFO - PROCESS | 1648 | 05:25:25 INFO - PROCESS | 1648 | 1481808325554 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] 05:27:38 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 05:27:40 INFO - PROCESS | 1648 | RSS: Main: 381370368 05:27:40 INFO - PROCESS | 1648 | 05:30:43 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 05:30:43 INFO - PROCESS | 1648 | RSS: Main: 349564928 05:30:43 INFO - PROCESS | 1648 | 05:33:46 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 05:33:46 INFO - PROCESS | 1648 | RSS: Main: 345075712 05:33:46 INFO - PROCESS | 1648 | 05:36:49 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 05:36:49 INFO - PROCESS | 1648 | RSS: Main: 316805120 05:36:49 INFO - PROCESS | 1648 | 05:39:53 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 05:39:53 INFO - PROCESS | 1648 | RSS: Main: 308043776 05:39:53 INFO - PROCESS | 1648 | 05:42:57 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 05:42:57 INFO - PROCESS | 1648 | RSS: Main: 417091584 05:42:57 INFO - PROCESS | 1648 | 05:42:57 INFO - PROCESS | 1648 | __start_tp_report 05:42:57 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 05:42:57 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 05:42:57 INFO - PROCESS | 1648 | |i|pagename|runs| 05:42:57 INFO - PROCESS | 1648 | |0;dojo.html;3179.157523256336;3229.901833376653;3327.883524076658;3339.3154403347316;3370.679785420384;5684.090545512719;5765.1928729977735;5793.333766616835;5804.738786754596;5810.6804125773115;5628.0713682242385;5681.545476361892;5685;5694.943050569493;5723.370429252779;5151.356080489939;5163.845084647466;5174.611904107198;5197.116490196676;5223.973880130594;5180.093483640367;5193.272941788153;5221.79112835488;5254.710990895499;5297.417284098766;5153.6392452528335;5222.556082732972;5249.42256351801;5289.973550132244;5302.3637163540425;5187.351581052369;5215.1134307167495;5226.712530810804;5233.293505376763;5272.789088436458;5125.3080834087295;5209.088409528355;5210.166373380279;5237.031149237365;5268.973655131738;5588.497035266805;5638.830835074954;5644.604877658566;5651.9717401413145;5708.372079071299;6181.474574661159;6223.315435302113;6232.626042437469;6253.6247825130185;6266.154069200645;6267.843303917393;6273.811785646438;6279.026750853579;6284.654344011077;6299.212598425197;5011.574016208663;5667.489925906688;5726.3700992890745;5730.541556675456;5794.159846822229;6186.257649082139;6224.844378890564;6228.719707613168;6242.75028998844;6260.467860231931;6109.716387860143;6124.234470691164;6127.019676851683;6139.10982907476;6141.969290153521;6143.109249158936;6153.4154255345675;6192.8142155734495;6197.876042479125;6226.968865155645;6233.158523599256;6236.126942228092;6236.812895613139;6239.188905442264;6246.718897649525;6234.875302493925;6264.5301602380005;6277;6288.9056664150075;6292.748290068346;4819.421669399694;4864.416270047617;4887.877803054952;4949.8515044548;4989.850304490871;4386.363977223285;4441.555844415532;4464.709793863389;4471.977640111843;4529.68292219548;5717.914231286534;5717.914231286534;5723;5832.358440571534;5853.2976042875125;6108.0992441897915;6294.842628934313;6316.715747791361;6317.810465686037;6320.55756097078;5675.77296908119;5737.196792449061;5744.51171650406;5789.9710501447225;5794.797182098607;5424.376196737347;5438.238646589481;5443.156310771837;5444.945550544523;5464.180372944011;6281.403266689657;6282.057691346335;6288.088227207121;6298.622082675054;6311.652859092702;6090.665013424127;6198.380161983766;6202.751889924352;6239.4696450800375;6288.622682639057;6176.66028368444;6242.219722534684;6271.310155882758;6298.1812364392335;6316.747330106744;6224.097505861715;6242.625442473466;6244.2819075806865;6255.218097737783;6264.248290205204;6122.295935967242;6146.832101900624;6262.09199666055;6276.435120839146;6282.2147231596055;5878.911816322673;5990.071538911476;6131.96934015327;6162.476189523762;6169.167162433014;6095.764916152122;6101.572889897665;6103.23709536308;6106.969465152646;6107.128129455892;5760.049591817303;5923.318818335946;5932.406759324033;5962.672053037125;5979.970100149645;5877.823665290048;5879.764809407576;5883.852903677443;5986;6005.909911351246;5781.884362312646;5794.420557944172;5798.5651076168615;5807.303123625192;5848.210491583582 05:42:57 INFO - PROCESS | 1648 | |1;ext.html;469.2187507799514;470.4025887123351;473.3302377136352;495.4203581809283;523.4922125538229;29720.95976640818;31352.216194595145;39317.80341098294;39796.40814367422;39812.203755924864;33542.832285838595;41683.16633667333;41784.582154178446;41810.163796724046;41842;9069.637214511411;9196;9215.815683686322;9237.95381023094;9251.028641992561;9210.49342286174;9316.301277404187;9326.1606455419;9330.720078397624;9331.766705832375;9261.351705380626;9328.440293582373;9338.766530836716;9339.95330023349;9346.906530934706;9255.676051338205;9332.906670933271;9334.346595738301;9335.299852511022;9356.625734970594;9163.496007719572;9232.446053236827;9243.445393276357;9253.953730231373;9259.490728009956;39129.80435097806;39443.01392465182;39647.8017609913;39666.60333396658;39666.60333396658;3200.487921932503;3221.500667396557;3285.0473362724783;3334.2664613784927;3336.6830151135846;28973.594781648175;29798;29931.25171870699;30126.54810177849;30226.51169986084;3104.5653608494827;3108.3783243351195;3178.7457003439667;3209.7271731902815;3241.7568682348683;425506.8724656388;489248;489731;492220.5388973068;493087;32671.509927351108;57298.42701573014;57657.711711441174;57736.422635773946;57890.131648025315;33462.66537334644;34512.82743586266;34704;34731.65268347284;34734.47898281528;13211.603651890458;13320.467181312639;13322.600321990356;13327.267000315076;13349.399277032557;11157.553697852158;11260.042896353902;11267.436628168558;11273.323600583828;11283.774324513464;7640.312371886557;7667.731629393056;7716.884246736304;7725.92274077252;7726.995490586188;3193.137852779783;3240.951385729216;3241.9837900810817;3247.431699452586;3265.869365225412;813.2639960835453;845.5518575155178;909.4725059465494;991.9107280344804;1028.3007554862766;26634.733652663614;28775;28899.855500722362;29513.704862951097;29622.851885740434;841.4782834642439;905.9275257979345;1045.1377613468767;1117.9105671546256;1132.546981207507;14421.711565768626;14825.332860021324;15107.093574385572;15200.619984500476;15246.542603721906;669705;676234;676241;676889;679394.6030269817;56766.432335676116;57433.71283143641;57538.424615753305;57581.424185757605;57858.8428231433;1048.402410625936;1277.565627686591;1365.221823560561;1384.9099808512617;1413.5405993052093;28568.57147142836;29794.553081703358;29954;29995.400091998476;30463.543046853873;48457.54627361185;49001.01997960092;49023.26465103098;49110.26334604912;49143.50856491389;544544.2772786269;546603;546769;547027;550224;26023.219303420927;26027.609585855847;26827;26829.597556036286;27604.585931211448;45461;45529.77235113803;45754.25474471542;46079;46170.53829461662;34847.65152348545;35228;36859.1025435929;36983.6301636991;39045.02387440337;37292.254154916205;37342.8132859334;37637;38054;38454.654087106814;37345.06637334089;38015.42976855294;38162;38269;38359;31689.36621267608;34206;34253.828730856185;34358;34430.31139377148;24924.50150997006;25300.873495632404;25367.98528058857;25523.23430297094;25723.485530289665 05:42:57 INFO - PROCESS | 1648 | |2;jquery.html;349.79187383506803;367.862051730601;370.84239198340697;381.3821608993431;397.87466947911406;14587;14862.033967792086;15555.53333399997;19198.424047278568;20428.28501002465;16893.66212675746;19012.14445349959;19929.20283188671;20112.798872011277;20304;5654.547636189116;5939.346671866091;5939.88120237595;5941.940580594203;5955.225820643332;4622.491525932145;5372.0330340538885;5821.18503409523;5885.293764748236;5898.882022359572;5832.241808564881;5834.883302333951;5834.970825145869;5837.795677151301;5845.970770146143;4403.933940990888;5764.538836893039;5815.127730840365;5849.61977471463;5873.823785286406;5483.479069488392;5642.4357564243655;5677.261955945701;5697.743601537941;5698.088305871082;12784.61646150617;13016.41426135826;13020.153690010215;13040.543580974716;15046.54860354191;2133.359992002393;2306.723193216808;2309.3187509684685;2531.139412599725;2558.590625499911;9316.767080822965;9483.715488535356;9692.95153524235;9980.650677226335;10144.137748291405;1702.1149002518746;1790.140732448419;1867.6824939760274;1910.169076451748;1916.8179022992792;106264.93735062628;112229;113301.433492832;114559;115245.4237728806;11003.944980275208;11269.21115521922;11383.88616113845;11440.771184576428;11447.08423326132;14595.343209555596;14619.78070328946;14627.926860365844;14646.26768661584;14657.04729192599;7735.303822655988;7769.261920117579;7871.685132594746;7881.133075361706;7961.243681850215;7358.080239970004;7386.741464048732;7401.148867880155;7515.736949206752;7521.8119547010665;4411.529543455675;4412.933805992912;4426.26966550522;4426.380306757057;4496.145732310851;1705.232645309591;1799.5591080185177;1863.5248011756914;1886.2549293029176;1897.2790339670837;709.0569542508451;713.5754226235382;825.896762904637;834.9165083491603;950.4677380666849;19283.325083622007;20494.38516844497;20873.791262087183;21017.789822101888;21157.624754390916;783.9886546355139;828.6271177969937;858.6651206029653;934.3226161033197;965.9951700241454;10134.847977280348;11051.447427628586;11065.612703555336;11068.3912384818;11146.665600032013;530407;618717;619572;630685;631758;106217.9378206208;123692;128374.35812820875;132892;136129.6387036117;995.5868314649484;1002.9247806414402;1013.878334599838;1015.791762688647;1017.2878984710732;14172.645683857985;16812.747808783122;18434.26262949467;18436.446906592824;18622.72065918985;53321.66695832635;53873.461265386846;54361.18458223209;54825;55535.72232138814;63248.05127922993;76195.61902190454;79913.00217494609;80535;81407.59296203482;5003.074431230234;6016.278046634432;6028.4272994065495;6597.967010164918;6874.587524748531;11439.142064345373;12204.938975305067;12420.813687794871;12818.55135070268;13041.739165216453;10555.261131721012;10688.305260158066;10777.461126943464;10818.945905270422;11049.502772375423;10560.630377936735;10604.469776511298;10631.468426578795;11007.394593297446;11041.779164416826;10487.423191724529;10845.620403285848;10939.781204376028;11058.281211721212;11109.66670999839;7736.574488403192;7857.960710196641;7893.644785984759;8062.274395304451;8343.624536895733;8067.475614085066;9999.450030248407;10640.414777187329;10725.785484290114;10857.619983300547 05:42:57 INFO - PROCESS | 1648 | |3;mootools.html;1340.8927285817133;1462.5758530026294;1601.9519414417564;1797.5056859363665;1833.7707786526685;7951.204879512046;8288.917110828897;8290.544020078893;8290.751277461686;8348.791280217998;7278.053852999103;7507.774766757006;7514.098308203023;7603.315701586856;8090.757277281677;1802.9549261268442;2075.16993202719;2111.68324751287;2298.2071185441077;2299.4366380236784;1928.7781905080956;2319.7796209360085;2330.5571941331177;2330.9417264568438;2333.101755824003;2247.550489902018;2287.4624463251207;2290;2295.5064661097863;2295.988520057397;2229.9554008919813;2252.76098146019;2278.100150440583;2290.484640955788;2292.4383526036063;2300;2303.608386574286;2322.291701031185;2324.8721320327377;2328.8020518255967;8170.632321545545;8237.299829514499;8300.584970751499;8306.792330191733;8320.37597180214;4274.700770946035;4918.262260660894;5082.695038297714;5087.135187018216;5091.210862316347;3395.3548825723033;3499.9475007874903;3519.8240087995496;3557.7153827693724;3562.305350456662;1042.593388578455;1062.2458054781114;1065.2436769893386;1079.578964203961;1081.691717860414;10752;10805.945970270099;10882.23824332297;10883.401412922287;10909.83635245464;3423.5207071010077;4355.760433176206;4377.8248870044845;4452.154090722753;4461.420015397977;8442.704505342406;8453.027901791373;8462.746117616482;8474;8478.788030299293;4991.201407774739;4994.101061808909;4995.375578052744;4995.425526064549;5011.09800235961;4507.4365704286965;4650.116477869194;4695.741734204583;4715.646326525456;4716.198246298138;3346.531485592019;3441.0881116504147;3456.084137703511;3588.2823435313044;3660.5424321959754;1091.3670071358592;1093.3549205968516;1093.3822390349428;1152.8558930133734;1160.5415860734963;716.3051839715572;835.7116794705817;860.8622620380709;887.454215657366;899.3839220134229;5059.2916991621205;5090.847274581768;5149.897002059938;5477.561795056466;5487.451254874481;724.3263764698776;851.2764150472173;887.9955600222087;915.922146617545;922.2898368256539;3267.575215222006;3595.496630471736;3611.7110631149444;3684.9916795346066;3719.0330514065995;9729;9962.950185249027;10062.547185376676;10066.396016239049;10087.546060427298;9907.950460247652;9909;9982.401055936667;10036.749081272881;10096;7545.207753185837;7636.847263054708;7702.075750909927;7761.223877612194;7775.1447340792465;4644;5309.973450132725;5423.457654234545;5425.83722488326;5429.375621803385;9142.314326425412;9207.815843682954;9263.76840578991;9371.812563748823;9375.437473751597;9195.356325057182;9285.071492850931;9290.349675522919;9336;9420.340576159602;6512.185976752906;6588.472922166311;6592.571482853599;6593.736250549924;6597.60414375139;6352.460040896391;7458.664360103917;7464.850702985801;7634.6946122154495;7644.967929329469;5898.439648233411;6077.149199112039;6853.383195512428;7016.929830701628;7057.752978645723;6797.456203503807;6922.446204303543;6925.549839260432;6960.4779641526075;6998.300169982961;6520.934790652223;6623.900641490285;6624.205095388585;6628.370304821035;6628.767993120217;4324.481062272547;4445.666575006952;4486.50648428666;4600.585947264763;4793.904121917472;4383.7808109595035;4679.672422930362;4687.296905464208;4895.583875370491;4939.530744579403 05:42:57 INFO - PROCESS | 1648 | |4;prototype.html;298.7878606189605;299.6598860293567;306.6136667798574;312.732613615359;313.510922960182;5112.156494178456;5237.45006774289;5294.470552944708;5360.410354860967;5375.220593014015;4843.730481924696;5039.72281524516;5137.049645815527;5143.485651434864;5152.355955505562;3990.122173121923;4004.859829905954;4012.678985681153;4023.6982226333;4055.9797201014026;3132.718055375016;3137.3882092991876;4002.919941601166;4043.6967227457912;4059.269331520325;3413.368526822546;3930.5676375598805;3941.9408708869246;3952.4861767970124;3992.141689536749;3407.7784943978563;3815.236952609489;3940.940885886714;3955.7033222508426;3958.584348643394;3064.0960916529675;3334.71654909333;3351.765376423651;3491.1621210909198;3503.562054743157;4110.979445102786;4677.707923117648;5072.670276432057;5114.803247970019;5174.697259719426;3778.9433158502643;4004.5194576650697;4266.488021437447;4514.9774251128865;4541.568550987651;4579.473360563511;5478.644183001308;5515.696636684981;5515.8692468043955;5532.5573954083575;3599.1362073102528;3797.164623782791;3813.103920578662;3828.3683192273243;3841.692664586826;4821.614270858393;5707.286589176353;5728.768314812293;5734.541236701054;5738.397468265814;3469.7495567929086;3552.751307408457;3710.461983012448;3846.769193848378;3866.284737323604;3288.67113288674;3589.2821435712963;3822.8470861165797;3886.980565097213;3908.5309762828642;3773.471713960048;4468.865934021985;4501.212287849678;4508.864734057984;4510.789713040652;4629.976850115795;5132.615053870974;5525.557955363562;5548.639338442988;5585.860353491195;1149.620625193684;1152.795328881321;1164.4585267850491;1241.6585439004346;1251.3055254333933;750.8873668949592;836.1513064239801;838.6393850644281;860.889452606131;869.2090197919863;4657;4752.718695026338;4909.656324057281;4957.975210123926;4962.727050012212;4942.935380064311;5079.704675307773;5394.8921021579345;5398.838034858961;5446.366090172933;5292.44429334922;5314;5322.281491998608;5350.759215835296;5365.1147560652835;2451.2523680277686;2825.7707897064843;2836.163331817119;2849.3588942487772;2852.8430936298287;4344.154578750923;5258.894822103536;5576.804811831566;5611.438856114438;5630.4369563043365;5500.614956952975;5533.833984980457;5542.584306177053;5547.805826796042;5587.469190426903;4594.1271158479785;4682.471465408709;5303.814366497154;5369.382521010056;5605.066252143086;4391.165678521071;4582.518835522222;4586.082783443391;5094.459350751193;5360.874216414597;4612.53874612536;4888.682235654671;4903.803847846046;5171.922421163761;5277.894442111213;5007.674501157413;5480.945190548044;5530.446955304437;5547.694876781816;5593.105103183471;3316.9484074650895;3523.3305671922253;3808.676262517717;3866.497355343787;3883.6893048556612;5648.406917273627;5676.460736230051;5689.971550142222;5716.42835716425;5722.427757224411;4971.95588926315;5479.397266300624;5498.890022199614;5572.35917869434;5625.974649126229;5164.7417629119145;5416.187571864252;5440.619156658996;5472.863178420412;5492.47821456961;4484.39460672805;5092.694438333712;5152.76812543444;5262.89474210506;5284.365876094893;4856.077345304381;5015.9968006399595;5069.5944324454695;5324.387695415076;5487.2015272210665;4318.632916202158;4473.105378924163;4548.454185497629;4792.568668819823;5410.891782164256 05:42:57 INFO - PROCESS | 1648 | |5;yui.html;702.6170736948362;755.8866170074488;763.3397111498552;785.5993443343895;796.1202870827739;15132.319045642958;15794.763078553831;15825.84174158258;15826.28781704823;15922.601934951617;14636.195009274512;14753.48362807302;14764.483243086494;14772.704545909075;14779.482718104868;665.4809248785955;688.2326206280012;818.3330585572754;843.5950743643054;847.1147650704997;681.9659017049128;812.0458461307969;829.7635173975418;839.1063517353998;840.865461526156;881.532787622561;885.7741275974618;886.3751055505885;887.3300658003199;888.8400087984161;697.069412334534;880.7225723896952;880.7445840706188;880.8678698195258;881.7354793561906;534.260049830983;534.5002422734756;535.4297672978305;535.6036532965617;626.7117126121989;13666;13753.862461375358;14119.576412707635;14122.082064665763;14129.010969232158;12933.547325843645;13066.608001759867;13209.207447553177;13220.933895330558;13283.402246898817;10336.741581460523;12281.877181228163;12294.323812190327;12314.199577027464;12357.876421235762;4330.220560299145;4365.80353884076;4430;4468.955310446887;4586.449626044896;92086.99999999933;92245.53877230638;93625.12749745035;95430.52284738602;96813.51593242059;9860.654877079267;10291.897081029243;10513.52689128991;10627.787444251226;10690.478190614014;20733.170673173205;20743.58512829765;20760.792392075888;20765.58468830615;20770.961451927342;7486;7489.13874904393;7640.197779233101;7695.807604809925;7737.187595302525;4103.8974025649595;4196.685248606305;4199.68502362324;4229.069604686964;4334.393184954109;3260.2012506935953;3315.966840331614;3326.3846188455213;3346.832658367072;3358.580177477815;627.9246490421178;638.5944924972675;642.5823214910346;643.8068579426154;648.6691787188473;524.4467087223017;603.7072020070262;614.2168734862996;629.6064959400375;631.7915088021034;7976.322012628992;8628.22345988864;9223.216026637676;9544.570494327772;9627.759306017406;497.6566169343142;647.1942431672504;668.6924014953177;696.3523922751788;708.256330852613;3556.87550935716;4502.212112880233;4522.954770452319;4540.614047805973;4642.373279607277;82540.58729706432;106024;107968.4601576987;110765;114126;61775;75206;77973;98247;100737.99262007432;68938.52291018842;76377.09057273502;78937.44781484846;79915.60042199751;82501;9252.629894804133;9673.516324183904;9811.901880981099;9858.457784821616;9863.704088877059;49265.50734492609;50945.74527127341;53119.672008200105;54723.179152311954;55554.94650607641;57151.42848571461;68260.3173968254;68941.3105868955;72258;76249.23750762422;12536.623901282612;12646.241225526497;12737.74524509486;12806.935965320114;13115.147515411467;13093.345332733135;13640.79538806899;13726.931365343109;14252.002359834712;14633.780493292395;12217.45021474018;12499.50445986125;13319.53381631677;13447.327633618475;13700.93149534246;12943.223406595635;13010.80483792725;13113.950883929072;13177.934110329386;13449.663758405726;12173.573924912585;12187.268763874195;12486.188397754117;12621;13131.803022954473;10401.81819994216;10602.734931626772;10733.034026937614;11161.776764464828;11179.441027948733;10708.89291107079;10874.565017399216;11206.439678015904;11264.60573879943;11539.8269025963 05:42:57 INFO - PROCESS | 1648 | __end_tp_report 05:42:57 INFO - PROCESS | 1648 | __start_cc_report 05:42:57 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1942 05:42:57 INFO - PROCESS | 1648 | __end_cc_report 05:42:57 INFO - PROCESS | 1648 | __startTimestamp1481809377399__endTimestamp 05:42:57 INFO - PROCESS | 1648 | 05:42:57 INFO - PROCESS | 1648 | ------- Summary: start ------- 05:42:57 INFO - PROCESS | 1648 | Number of tests: 6 05:42:57 INFO - PROCESS | 1648 | 05:42:57 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5735.12 Median:5878.91 stddev:641.81 (10.9%) stddev-sans-first:611.66 05:42:57 INFO - PROCESS | 1648 | Values: 3179.2 3229.9 3327.9 3339.3 3370.7 5684.1 5765.2 5793.3 5804.7 5810.7 5628.1 5681.5 5685.0 5694.9 5723.4 5151.4 5163.8 5174.6 5197.1 5224.0 5180.1 5193.3 5221.8 5254.7 5297.4 5153.6 5222.6 5249.4 5290.0 5302.4 5187.4 5215.1 5226.7 5233.3 5272.8 5125.3 5209.1 5210.2 5237.0 5269.0 5588.5 5638.8 5644.6 5652.0 5708.4 6181.5 6223.3 6232.6 6253.6 6266.2 6267.8 6273.8 6279.0 6284.7 6299.2 5011.6 5667.5 5726.4 5730.5 5794.2 6186.3 6224.8 6228.7 6242.8 6260.5 6109.7 6124.2 6127.0 6139.1 6142.0 6143.1 6153.4 6192.8 6197.9 6227.0 6233.2 6236.1 6236.8 6239.2 6246.7 6234.9 6264.5 6277.0 6288.9 6292.7 4819.4 4864.4 4887.9 4949.9 4989.9 4386.4 4441.6 4464.7 4472.0 4529.7 5717.9 5717.9 5723.0 5832.4 5853.3 6108.1 6294.8 6316.7 6317.8 6320.6 5675.8 5737.2 5744.5 5790.0 5794.8 5424.4 5438.2 5443.2 5444.9 5464.2 6281.4 6282.1 6288.1 6298.6 6311.7 6090.7 6198.4 6202.8 6239.5 6288.6 6176.7 6242.2 6271.3 6298.2 6316.7 6224.1 6242.6 6244.3 6255.2 6264.2 6122.3 6146.8 6262.1 6276.4 6282.2 5878.9 5990.1 6132.0 6162.5 6169.2 6095.8 6101.6 6103.2 6107.0 6107.1 5760.0 5923.3 5932.4 5962.7 5980.0 5877.8 5879.8 5883.9 5986.0 6005.9 5781.9 5794.4 5798.6 5807.3 5848.2 05:42:57 INFO - PROCESS | 1648 | 05:42:57 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:68028.32 Median:38406.83 stddev:153639.49 (400.0%) stddev-sans-first:153986.75 05:42:57 INFO - PROCESS | 1648 | Values: 469.2 470.4 473.3 495.4 523.5 29721.0 31352.2 39317.8 39796.4 39812.2 33542.8 41683.2 41784.6 41810.2 41842.0 9069.6 9196.0 9215.8 9238.0 9251.0 9210.5 9316.3 9326.2 9330.7 9331.8 9261.4 9328.4 9338.8 9340.0 9346.9 9255.7 9332.9 9334.3 9335.3 9356.6 9163.5 9232.4 9243.4 9254.0 9259.5 39129.8 39443.0 39647.8 39666.6 39666.6 3200.5 3221.5 3285.0 3334.3 3336.7 28973.6 29798.0 29931.3 30126.5 30226.5 3104.6 3108.4 3178.7 3209.7 3241.8 425506.9 489248.0 489731.0 492220.5 493087.0 32671.5 57298.4 57657.7 57736.4 57890.1 33462.7 34512.8 34704.0 34731.7 34734.5 13211.6 13320.5 13322.6 13327.3 13349.4 11157.6 11260.0 11267.4 11273.3 11283.8 7640.3 7667.7 7716.9 7725.9 7727.0 3193.1 3241.0 3242.0 3247.4 3265.9 813.3 845.6 909.5 991.9 1028.3 26634.7 28775.0 28899.9 29513.7 29622.9 841.5 905.9 1045.1 1117.9 1132.5 14421.7 14825.3 15107.1 15200.6 15246.5 669705.0 676234.0 676241.0 676889.0 679394.6 56766.4 57433.7 57538.4 57581.4 57858.8 1048.4 1277.6 1365.2 1384.9 1413.5 28568.6 29794.6 29954.0 29995.4 30463.5 48457.5 49001.0 49023.3 49110.3 49143.5 544544.3 546603.0 546769.0 547027.0 550224.0 26023.2 26027.6 26827.0 26829.6 27604.6 45461.0 45529.8 45754.3 46079.0 46170.5 34847.7 35228.0 36859.1 36983.6 39045.0 37292.3 37342.8 37637.0 38054.0 38454.7 37345.1 38015.4 38162.0 38269.0 38359.0 31689.4 34206.0 34253.8 34358.0 34430.3 24924.5 25300.9 25368.0 25523.2 25723.5 05:42:57 INFO - PROCESS | 1648 | 05:42:57 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:34300.75 Median:2420.23 stddev:101258.29 (4183.8%) stddev-sans-first:101510.25 05:42:57 INFO - PROCESS | 1648 | Values: 349.8 367.9 370.8 381.4 397.9 14587.0 14862.0 15555.5 19198.4 20428.3 16893.7 19012.1 19929.2 20112.8 20304.0 5654.5 5939.3 5939.9 5941.9 5955.2 4622.5 5372.0 5821.2 5885.3 5898.9 5832.2 5834.9 5835.0 5837.8 5846.0 4403.9 5764.5 5815.1 5849.6 5873.8 5483.5 5642.4 5677.3 5697.7 5698.1 12784.6 13016.4 13020.2 13040.5 15046.5 2133.4 2306.7 2309.3 2531.1 2558.6 9316.8 9483.7 9693.0 9980.7 10144.1 1702.1 1790.1 1867.7 1910.2 1916.8 106264.9 112229.0 113301.4 114559.0 115245.4 11003.9 11269.2 11383.9 11440.8 11447.1 14595.3 14619.8 14627.9 14646.3 14657.0 7735.3 7769.3 7871.7 7881.1 7961.2 7358.1 7386.7 7401.1 7515.7 7521.8 4411.5 4412.9 4426.3 4426.4 4496.1 1705.2 1799.6 1863.5 1886.3 1897.3 709.1 713.6 825.9 834.9 950.5 19283.3 20494.4 20873.8 21017.8 21157.6 784.0 828.6 858.7 934.3 966.0 10134.8 11051.4 11065.6 11068.4 11146.7 530407.0 618717.0 619572.0 630685.0 631758.0 106217.9 123692.0 128374.4 132892.0 136129.6 995.6 1002.9 1013.9 1015.8 1017.3 14172.6 16812.7 18434.3 18436.4 18622.7 53321.7 53873.5 54361.2 54825.0 55535.7 63248.1 76195.6 79913.0 80535.0 81407.6 5003.1 6016.3 6028.4 6598.0 6874.6 11439.1 12204.9 12420.8 12818.6 13041.7 10555.3 10688.3 10777.5 10818.9 11049.5 10560.6 10604.5 10631.5 11007.4 11041.8 10487.4 10845.6 10939.8 11058.3 11109.7 7736.6 7858.0 7893.6 8062.3 8343.6 8067.5 9999.5 10640.4 10725.8 10857.6 05:42:57 INFO - PROCESS | 1648 | 05:42:57 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5262.43 Median:4995.40 stddev:2919.70 (58.4%) stddev-sans-first:2913.02 05:42:57 INFO - PROCESS | 1648 | Values: 1340.9 1462.6 1602.0 1797.5 1833.8 7951.2 8288.9 8290.5 8290.8 8348.8 7278.1 7507.8 7514.1 7603.3 8090.8 1803.0 2075.2 2111.7 2298.2 2299.4 1928.8 2319.8 2330.6 2330.9 2333.1 2247.6 2287.5 2290.0 2295.5 2296.0 2230.0 2252.8 2278.1 2290.5 2292.4 2300.0 2303.6 2322.3 2324.9 2328.8 8170.6 8237.3 8300.6 8306.8 8320.4 4274.7 4918.3 5082.7 5087.1 5091.2 3395.4 3499.9 3519.8 3557.7 3562.3 1042.6 1062.2 1065.2 1079.6 1081.7 10752.0 10805.9 10882.2 10883.4 10909.8 3423.5 4355.8 4377.8 4452.2 4461.4 8442.7 8453.0 8462.7 8474.0 8478.8 4991.2 4994.1 4995.4 4995.4 5011.1 4507.4 4650.1 4695.7 4715.6 4716.2 3346.5 3441.1 3456.1 3588.3 3660.5 1091.4 1093.4 1093.4 1152.9 1160.5 716.3 835.7 860.9 887.5 899.4 5059.3 5090.8 5149.9 5477.6 5487.5 724.3 851.3 888.0 915.9 922.3 3267.6 3595.5 3611.7 3685.0 3719.0 9729.0 9963.0 10062.5 10066.4 10087.5 9908.0 9909.0 9982.4 10036.7 10096.0 7545.2 7636.8 7702.1 7761.2 7775.1 4644.0 5310.0 5423.5 5425.8 5429.4 9142.3 9207.8 9263.8 9371.8 9375.4 9195.4 9285.1 9290.3 9336.0 9420.3 6512.2 6588.5 6592.6 6593.7 6597.6 6352.5 7458.7 7464.9 7634.7 7645.0 5898.4 6077.1 6853.4 7016.9 7057.8 6797.5 6922.4 6925.5 6960.5 6998.3 6520.9 6623.9 6624.2 6628.4 6628.8 4324.5 4445.7 4486.5 4600.6 4793.9 4383.8 4679.7 4687.3 4895.6 4939.5 05:42:57 INFO - PROCESS | 1648 | 05:42:57 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4348.46 Median:4872.38 stddev:1338.12 (27.5%) stddev-sans-first:1306.90 05:42:57 INFO - PROCESS | 1648 | Values: 298.8 299.7 306.6 312.7 313.5 5112.2 5237.5 5294.5 5360.4 5375.2 4843.7 5039.7 5137.0 5143.5 5152.4 3990.1 4004.9 4012.7 4023.7 4056.0 3132.7 3137.4 4002.9 4043.7 4059.3 3413.4 3930.6 3941.9 3952.5 3992.1 3407.8 3815.2 3940.9 3955.7 3958.6 3064.1 3334.7 3351.8 3491.2 3503.6 4111.0 4677.7 5072.7 5114.8 5174.7 3778.9 4004.5 4266.5 4515.0 4541.6 4579.5 5478.6 5515.7 5515.9 5532.6 3599.1 3797.2 3813.1 3828.4 3841.7 4821.6 5707.3 5728.8 5734.5 5738.4 3469.7 3552.8 3710.5 3846.8 3866.3 3288.7 3589.3 3822.8 3887.0 3908.5 3773.5 4468.9 4501.2 4508.9 4510.8 4630.0 5132.6 5525.6 5548.6 5585.9 1149.6 1152.8 1164.5 1241.7 1251.3 750.9 836.2 838.6 860.9 869.2 4657.0 4752.7 4909.7 4958.0 4962.7 4942.9 5079.7 5394.9 5398.8 5446.4 5292.4 5314.0 5322.3 5350.8 5365.1 2451.3 2825.8 2836.2 2849.4 2852.8 4344.2 5258.9 5576.8 5611.4 5630.4 5500.6 5533.8 5542.6 5547.8 5587.5 4594.1 4682.5 5303.8 5369.4 5605.1 4391.2 4582.5 4586.1 5094.5 5360.9 4612.5 4888.7 4903.8 5171.9 5277.9 5007.7 5480.9 5530.4 5547.7 5593.1 3316.9 3523.3 3808.7 3866.5 3883.7 5648.4 5676.5 5690.0 5716.4 5722.4 4972.0 5479.4 5498.9 5572.4 5626.0 5164.7 5416.2 5440.6 5472.9 5492.5 4484.4 5092.7 5152.8 5262.9 5284.4 4856.1 5016.0 5069.6 5324.4 5487.2 4318.6 4473.1 4548.5 4792.6 5410.9 05:42:57 INFO - PROCESS | 1648 | 05:42:57 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:19909.67 Median:4531.78 stddev:28507.16 (629.0%) stddev-sans-first:28550.65 05:42:57 INFO - PROCESS | 1648 | Values: 702.6 755.9 763.3 785.6 796.1 15132.3 15794.8 15825.8 15826.3 15922.6 14636.2 14753.5 14764.5 14772.7 14779.5 665.5 688.2 818.3 843.6 847.1 682.0 812.0 829.8 839.1 840.9 881.5 885.8 886.4 887.3 888.8 697.1 880.7 880.7 880.9 881.7 534.3 534.5 535.4 535.6 626.7 13666.0 13753.9 14119.6 14122.1 14129.0 12933.5 13066.6 13209.2 13220.9 13283.4 10336.7 12281.9 12294.3 12314.2 12357.9 4330.2 4365.8 4430.0 4469.0 4586.4 92087.0 92245.5 93625.1 95430.5 96813.5 9860.7 10291.9 10513.5 10627.8 10690.5 20733.2 20743.6 20760.8 20765.6 20771.0 7486.0 7489.1 7640.2 7695.8 7737.2 4103.9 4196.7 4199.7 4229.1 4334.4 3260.2 3316.0 3326.4 3346.8 3358.6 627.9 638.6 642.6 643.8 648.7 524.4 603.7 614.2 629.6 631.8 7976.3 8628.2 9223.2 9544.6 9627.8 497.7 647.2 668.7 696.4 708.3 3556.9 4502.2 4523.0 4540.6 4642.4 82540.6 106024.0 107968.5 110765.0 114126.0 61775.0 75206.0 77973.0 98247.0 100738.0 68938.5 76377.1 78937.4 79915.6 82501.0 9252.6 9673.5 9811.9 9858.5 9863.7 49265.5 50945.7 53119.7 54723.2 55554.9 57151.4 68260.3 68941.3 72258.0 76249.2 12536.6 12646.2 12737.7 12806.9 13115.1 13093.3 13640.8 13726.9 14252.0 14633.8 12217.5 12499.5 13319.5 13447.3 13700.9 12943.2 13010.8 13114.0 13177.9 13449.7 12173.6 12187.3 12486.2 12621.0 13131.8 10401.8 10602.7 10733.0 11161.8 11179.4 10708.9 10874.6 11206.4 11264.6 11539.8 05:42:57 INFO - PROCESS | 1648 | -------- Summary: end -------- 05:42:57 INFO - PROCESS | 1648 | 05:42:58 INFO - TEST-INFO | 1648: exit 0 05:42:58 INFO - TEST-OK | dromaeo_css | took 1120570ms 05:42:58 INFO - TEST-START | kraken 05:42:58 INFO - operating with platform_type : mac_ 05:42:58 INFO - Initialising browser for kraken test... 05:42:58 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpJGpa7D/profile http://localhost:49236/getInfo.html) 05:43:00 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 05:43:00 INFO - PROCESS | 1654 | colorDepth:24 05:43:00 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 05:43:00 INFO - PROCESS | 1654 | __metrics 05:43:00 INFO - PROCESS | 1654 | 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" 05:43:00 INFO - PROCESS | 1654 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:43:01 INFO - TEST-INFO | 1654: exit 0 05:43:01 INFO - Browser initialized. 05:43:01 INFO - Running cycle 1/1 for kraken test... 05:43:01 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpJGpa7D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:43:13 INFO - PROCESS | 1655 | RSS: Main: 222732288 05:43:13 INFO - PROCESS | 1655 | 05:43:16 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 05:43:17 INFO - PROCESS | 1655 | RSS: Main: 272121856 05:43:17 INFO - PROCESS | 1655 | 05:43:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 05:43:20 INFO - PROCESS | 1655 | RSS: Main: 283779072 05:43:20 INFO - PROCESS | 1655 | 05:43:23 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 05:43:24 INFO - PROCESS | 1655 | RSS: Main: 380669952 05:43:24 INFO - PROCESS | 1655 | 05:43:26 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 05:43:27 INFO - PROCESS | 1655 | RSS: Main: 283406336 05:43:27 INFO - PROCESS | 1655 | 05:43:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:43:29 INFO - PROCESS | 1655 | RSS: Main: 284749824 05:43:29 INFO - PROCESS | 1655 | 05:43:32 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 05:43:33 INFO - PROCESS | 1655 | RSS: Main: 324808704 05:43:33 INFO - PROCESS | 1655 | 05:43:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 05:43:36 INFO - PROCESS | 1655 | RSS: Main: 326205440 05:43:36 INFO - PROCESS | 1655 | 05:43:39 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 05:43:40 INFO - PROCESS | 1655 | RSS: Main: 323297280 05:43:40 INFO - PROCESS | 1655 | 05:43:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:43:42 INFO - PROCESS | 1655 | RSS: Main: 300187648 05:43:42 INFO - PROCESS | 1655 | 05:43:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:43:44 INFO - PROCESS | 1655 | RSS: Main: 300064768 05:43:44 INFO - PROCESS | 1655 | 05:43:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:43:47 INFO - PROCESS | 1655 | RSS: Main: 303280128 05:43:47 INFO - PROCESS | 1655 | 05:43:50 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:43:51 INFO - PROCESS | 1655 | RSS: Main: 319049728 05:43:51 INFO - PROCESS | 1655 | 05:43:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:43:54 INFO - PROCESS | 1655 | RSS: Main: 312860672 05:43:54 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 05:43:56 INFO - PROCESS | 1655 | RSS: Main: 356790272 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | __start_tp_report 05:43:56 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 05:43:56 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 05:43:56 INFO - PROCESS | 1655 | |i|pagename|runs| 05:43:56 INFO - PROCESS | 1655 | |0;ai-astar;114;115;115;113;115;115;113;114;114;114 05:43:56 INFO - PROCESS | 1655 | |1;audio-beat-detection;144;113;115;176;139;127;110;141;175;138 05:43:56 INFO - PROCESS | 1655 | |2;audio-dft;184;185;183;185;183;182;184;182;185;183 05:43:56 INFO - PROCESS | 1655 | |3;audio-fft;81;60;60;90;62;59;59;60;100;82 05:43:56 INFO - PROCESS | 1655 | |4;audio-oscillator;90;69;95;70;68;69;88;68;69;92 05:43:56 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;93;131;87;85;87;83;87;85;86;84 05:43:56 INFO - PROCESS | 1655 | |6;imaging-darkroom;93;108;149;127;93;94;92;93;93;92 05:43:56 INFO - PROCESS | 1655 | |7;imaging-desaturate;81;81;79;81;84;82;84;85;81;80 05:43:56 INFO - PROCESS | 1655 | |8;json-parse-financial;56;56;53;52;57;58;59;57;56;57 05:43:56 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;64;71;68;76;71;69;69;70;71;72 05:43:56 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;83;85;84;84;81;83;83;82;83;96 05:43:56 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;138;139;141;146;153;154;162;145;183;169 05:43:56 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;162;164;165;166;166;165;163;166;163;164 05:43:56 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;68;68;67;69;66;68;68;69;80;79 05:43:56 INFO - PROCESS | 1655 | __end_tp_report 05:43:56 INFO - PROCESS | 1655 | __start_cc_report 05:43:56 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2138 05:43:56 INFO - PROCESS | 1655 | __end_cc_report 05:43:56 INFO - PROCESS | 1655 | __startTimestamp1481809436880__endTimestamp 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | ------- Summary: start ------- 05:43:56 INFO - PROCESS | 1655 | Number of tests: 14 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.50 stddev:0.79 (0.7%) stddev-sans-first:0.83 05:43:56 INFO - PROCESS | 1655 | Values: 114.0 115.0 115.0 113.0 115.0 115.0 113.0 114.0 114.0 114.0 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:140.00 stddev:23.38 (16.7%) stddev-sans-first:24.69 05:43:56 INFO - PROCESS | 1655 | Values: 144.0 113.0 115.0 176.0 139.0 127.0 110.0 141.0 175.0 138.0 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.17 (0.6%) stddev-sans-first:1.24 05:43:56 INFO - PROCESS | 1655 | Values: 184.0 185.0 183.0 185.0 183.0 182.0 184.0 182.0 185.0 183.0 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:71.30 Median:61.00 stddev:15.47 (25.4%) stddev-sans-first:16.01 05:43:56 INFO - PROCESS | 1655 | Values: 81.0 60.0 60.0 90.0 62.0 59.0 59.0 60.0 100.0 82.0 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.00 stddev:11.72 (14.8%) stddev-sans-first:11.57 05:43:56 INFO - PROCESS | 1655 | Values: 90.0 69.0 95.0 70.0 68.0 69.0 88.0 68.0 69.0 92.0 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:86.50 stddev:14.38 (16.6%) stddev-sans-first:15.23 05:43:56 INFO - PROCESS | 1655 | Values: 93.0 131.0 87.0 85.0 87.0 83.0 87.0 85.0 86.0 84.0 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:103.40 Median:93.00 stddev:19.55 (21.0%) stddev-sans-first:20.37 05:43:56 INFO - PROCESS | 1655 | Values: 93.0 108.0 149.0 127.0 93.0 94.0 92.0 93.0 93.0 92.0 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:81.80 Median:81.50 stddev:1.93 (2.4%) stddev-sans-first:2.03 05:43:56 INFO - PROCESS | 1655 | Values: 81.0 81.0 79.0 81.0 84.0 82.0 84.0 85.0 81.0 80.0 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:57.00 stddev:2.13 (3.7%) stddev-sans-first:2.26 05:43:56 INFO - PROCESS | 1655 | Values: 56.0 56.0 53.0 52.0 57.0 58.0 59.0 57.0 56.0 57.0 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:71.00 stddev:3.07 (4.3%) stddev-sans-first:2.33 05:43:56 INFO - PROCESS | 1655 | Values: 64.0 71.0 68.0 76.0 71.0 69.0 69.0 70.0 71.0 72.0 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:83.50 stddev:4.22 (5.1%) stddev-sans-first:4.45 05:43:56 INFO - PROCESS | 1655 | Values: 83.0 85.0 84.0 84.0 81.0 83.0 83.0 82.0 83.0 96.0 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:153.00 Median:153.50 stddev:14.59 (9.5%) stddev-sans-first:14.43 05:43:56 INFO - PROCESS | 1655 | Values: 138.0 139.0 141.0 146.0 153.0 154.0 162.0 145.0 183.0 169.0 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.40 Median:165.00 stddev:1.43 (0.9%) stddev-sans-first:1.22 05:43:56 INFO - PROCESS | 1655 | Values: 162.0 164.0 165.0 166.0 166.0 165.0 163.0 166.0 163.0 164.0 05:43:56 INFO - PROCESS | 1655 | 05:43:56 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:68.50 stddev:4.98 (7.3%) stddev-sans-first:5.22 05:43:56 INFO - PROCESS | 1655 | Values: 68.0 68.0 67.0 69.0 66.0 68.0 68.0 69.0 80.0 79.0 05:43:56 INFO - PROCESS | 1655 | -------- Summary: end -------- 05:43:56 INFO - PROCESS | 1655 | 05:43:57 INFO - TEST-INFO | 1655: exit 0 05:43:57 INFO - TEST-OK | kraken | took 59896ms 05:43:57 INFO - SUITE-END | took 1180s 05:43:58 INFO - Completed test suite (00:19:40) 05:43:58 INFO - javascript benchmark 05:43:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3179.157523256336, 3229.901833376653, 3327.883524076658, 3339.3154403347316, 3370.679785420384, 5684.090545512719, 5765.1928729977735, 5793.333766616835, 5804.738786754596, 5810.6804125773115, 5628.0713682242385, 5681.545476361892, 5685.0, 5694.943050569493, 5723.370429252779, 5151.356080489939, 5163.845084647466, 5174.611904107198, 5197.116490196676, 5223.973880130594, 5180.093483640367, 5193.272941788153, 5221.79112835488, 5254.710990895499, 5297.417284098766, 5153.6392452528335, 5222.556082732972, 5249.42256351801, 5289.973550132244, 5302.3637163540425, 5187.351581052369, 5215.1134307167495, 5226.712530810804, 5233.293505376763, 5272.789088436458, 5125.3080834087295, 5209.088409528355, 5210.166373380279, 5237.031149237365, 5268.973655131738, 5588.497035266805, 5638.830835074954, 5644.604877658566, 5651.9717401413145, 5708.372079071299, 6181.474574661159, 6223.315435302113, 6232.626042437469, 6253.6247825130185, 6266.154069200645, 6267.843303917393, 6273.811785646438, 6279.026750853579, 6284.654344011077, 6299.212598425197, 5011.574016208663, 5667.489925906688, 5726.3700992890745, 5730.541556675456, 5794.159846822229, 6186.257649082139, 6224.844378890564, 6228.719707613168, 6242.75028998844, 6260.467860231931, 6109.716387860143, 6124.234470691164, 6127.019676851683, 6139.10982907476, 6141.969290153521, 6143.109249158936, 6153.4154255345675, 6192.8142155734495, 6197.876042479125, 6226.968865155645, 6233.158523599256, 6236.126942228092, 6236.812895613139, 6239.188905442264, 6246.718897649525, 6234.875302493925, 6264.5301602380005, 6277.0, 6288.9056664150075, 6292.748290068346, 4819.421669399694, 4864.416270047617, 4887.877803054952, 4949.8515044548, 4989.850304490871, 4386.363977223285, 4441.555844415532, 4464.709793863389, 4471.977640111843, 4529.68292219548, 5717.914231286534, 5717.914231286534, 5723.0, 5832.358440571534, 5853.2976042875125, 6108.0992441897915, 6294.842628934313, 6316.715747791361, 6317.810465686037, 6320.55756097078, 5675.77296908119, 5737.196792449061, 5744.51171650406, 5789.9710501447225, 5794.797182098607, 5424.376196737347, 5438.238646589481, 5443.156310771837, 5444.945550544523, 5464.180372944011, 6281.403266689657, 6282.057691346335, 6288.088227207121, 6298.622082675054, 6311.652859092702, 6090.665013424127, 6198.380161983766, 6202.751889924352, 6239.4696450800375, 6288.622682639057, 6176.66028368444, 6242.219722534684, 6271.310155882758, 6298.1812364392335, 6316.747330106744, 6224.097505861715, 6242.625442473466, 6244.2819075806865, 6255.218097737783, 6264.248290205204, 6122.295935967242, 6146.832101900624, 6262.09199666055, 6276.435120839146, 6282.2147231596055, 5878.911816322673, 5990.071538911476, 6131.96934015327, 6162.476189523762, 6169.167162433014, 6095.764916152122, 6101.572889897665, 6103.23709536308, 6106.969465152646, 6107.128129455892, 5760.049591817303, 5923.318818335946, 5932.406759324033, 5962.672053037125, 5979.970100149645, 5877.823665290048, 5879.764809407576, 5883.852903677443, 5986.0, 6005.909911351246, 5781.884362312646, 5794.420557944172, 5798.5651076168615, 5807.303123625192, 5848.210491583582], "value": 5692.620221050239, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.2187507799514, 470.4025887123351, 473.3302377136352, 495.4203581809283, 523.4922125538229, 29720.95976640818, 31352.216194595145, 39317.80341098294, 39796.40814367422, 39812.203755924864, 33542.832285838595, 41683.16633667333, 41784.582154178446, 41810.163796724046, 41842.0, 9069.637214511411, 9196.0, 9215.815683686322, 9237.95381023094, 9251.028641992561, 9210.49342286174, 9316.301277404187, 9326.1606455419, 9330.720078397624, 9331.766705832375, 9261.351705380626, 9328.440293582373, 9338.766530836716, 9339.95330023349, 9346.906530934706, 9255.676051338205, 9332.906670933271, 9334.346595738301, 9335.299852511022, 9356.625734970594, 9163.496007719572, 9232.446053236827, 9243.445393276357, 9253.953730231373, 9259.490728009956, 39129.80435097806, 39443.01392465182, 39647.8017609913, 39666.60333396658, 39666.60333396658, 3200.487921932503, 3221.500667396557, 3285.0473362724783, 3334.2664613784927, 3336.6830151135846, 28973.594781648175, 29798.0, 29931.25171870699, 30126.54810177849, 30226.51169986084, 3104.5653608494827, 3108.3783243351195, 3178.7457003439667, 3209.7271731902815, 3241.7568682348683, 425506.8724656388, 489248.0, 489731.0, 492220.5388973068, 493087.0, 32671.509927351108, 57298.42701573014, 57657.711711441174, 57736.422635773946, 57890.131648025315, 33462.66537334644, 34512.82743586266, 34704.0, 34731.65268347284, 34734.47898281528, 13211.603651890458, 13320.467181312639, 13322.600321990356, 13327.267000315076, 13349.399277032557, 11157.553697852158, 11260.042896353902, 11267.436628168558, 11273.323600583828, 11283.774324513464, 7640.312371886557, 7667.731629393056, 7716.884246736304, 7725.92274077252, 7726.995490586188, 3193.137852779783, 3240.951385729216, 3241.9837900810817, 3247.431699452586, 3265.869365225412, 813.2639960835453, 845.5518575155178, 909.4725059465494, 991.9107280344804, 1028.3007554862766, 26634.733652663614, 28775.0, 28899.855500722362, 29513.704862951097, 29622.851885740434, 841.4782834642439, 905.9275257979345, 1045.1377613468767, 1117.9105671546256, 1132.546981207507, 14421.711565768626, 14825.332860021324, 15107.093574385572, 15200.619984500476, 15246.542603721906, 669705.0, 676234.0, 676241.0, 676889.0, 679394.6030269817, 56766.432335676116, 57433.71283143641, 57538.424615753305, 57581.424185757605, 57858.8428231433, 1048.402410625936, 1277.565627686591, 1365.221823560561, 1384.9099808512617, 1413.5405993052093, 28568.57147142836, 29794.553081703358, 29954.0, 29995.400091998476, 30463.543046853873, 48457.54627361185, 49001.01997960092, 49023.26465103098, 49110.26334604912, 49143.50856491389, 544544.2772786269, 546603.0, 546769.0, 547027.0, 550224.0, 26023.219303420927, 26027.609585855847, 26827.0, 26829.597556036286, 27604.585931211448, 45461.0, 45529.77235113803, 45754.25474471542, 46079.0, 46170.53829461662, 34847.65152348545, 35228.0, 36859.1025435929, 36983.6301636991, 39045.02387440337, 37292.254154916205, 37342.8132859334, 37637.0, 38054.0, 38454.654087106814, 37345.06637334089, 38015.42976855294, 38162.0, 38269.0, 38359.0, 31689.36621267608, 34206.0, 34253.828730856185, 34358.0, 34430.31139377148, 24924.50150997006, 25300.873495632404, 25367.98528058857, 25523.23430297094, 25723.485530289665], "value": 18040.57097807662, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.79187383506803, 367.862051730601, 370.84239198340697, 381.3821608993431, 397.87466947911406, 14587.0, 14862.033967792086, 15555.53333399997, 19198.424047278568, 20428.28501002465, 16893.66212675746, 19012.14445349959, 19929.20283188671, 20112.798872011277, 20304.0, 5654.547636189116, 5939.346671866091, 5939.88120237595, 5941.940580594203, 5955.225820643332, 4622.491525932145, 5372.0330340538885, 5821.18503409523, 5885.293764748236, 5898.882022359572, 5832.241808564881, 5834.883302333951, 5834.970825145869, 5837.795677151301, 5845.970770146143, 4403.933940990888, 5764.538836893039, 5815.127730840365, 5849.61977471463, 5873.823785286406, 5483.479069488392, 5642.4357564243655, 5677.261955945701, 5697.743601537941, 5698.088305871082, 12784.61646150617, 13016.41426135826, 13020.153690010215, 13040.543580974716, 15046.54860354191, 2133.359992002393, 2306.723193216808, 2309.3187509684685, 2531.139412599725, 2558.590625499911, 9316.767080822965, 9483.715488535356, 9692.95153524235, 9980.650677226335, 10144.137748291405, 1702.1149002518746, 1790.140732448419, 1867.6824939760274, 1910.169076451748, 1916.8179022992792, 106264.93735062628, 112229.0, 113301.433492832, 114559.0, 115245.4237728806, 11003.944980275208, 11269.21115521922, 11383.88616113845, 11440.771184576428, 11447.08423326132, 14595.343209555596, 14619.78070328946, 14627.926860365844, 14646.26768661584, 14657.04729192599, 7735.303822655988, 7769.261920117579, 7871.685132594746, 7881.133075361706, 7961.243681850215, 7358.080239970004, 7386.741464048732, 7401.148867880155, 7515.736949206752, 7521.8119547010665, 4411.529543455675, 4412.933805992912, 4426.26966550522, 4426.380306757057, 4496.145732310851, 1705.232645309591, 1799.5591080185177, 1863.5248011756914, 1886.2549293029176, 1897.2790339670837, 709.0569542508451, 713.5754226235382, 825.896762904637, 834.9165083491603, 950.4677380666849, 19283.325083622007, 20494.38516844497, 20873.791262087183, 21017.789822101888, 21157.624754390916, 783.9886546355139, 828.6271177969937, 858.6651206029653, 934.3226161033197, 965.9951700241454, 10134.847977280348, 11051.447427628586, 11065.612703555336, 11068.3912384818, 11146.665600032013, 530407.0, 618717.0, 619572.0, 630685.0, 631758.0, 106217.9378206208, 123692.0, 128374.35812820875, 132892.0, 136129.6387036117, 995.5868314649484, 1002.9247806414402, 1013.878334599838, 1015.791762688647, 1017.2878984710732, 14172.645683857985, 16812.747808783122, 18434.26262949467, 18436.446906592824, 18622.72065918985, 53321.66695832635, 53873.461265386846, 54361.18458223209, 54825.0, 55535.72232138814, 63248.05127922993, 76195.61902190454, 79913.00217494609, 80535.0, 81407.59296203482, 5003.074431230234, 6016.278046634432, 6028.4272994065495, 6597.967010164918, 6874.587524748531, 11439.142064345373, 12204.938975305067, 12420.813687794871, 12818.55135070268, 13041.739165216453, 10555.261131721012, 10688.305260158066, 10777.461126943464, 10818.945905270422, 11049.502772375423, 10560.630377936735, 10604.469776511298, 10631.468426578795, 11007.394593297446, 11041.779164416826, 10487.423191724529, 10845.620403285848, 10939.781204376028, 11058.281211721212, 11109.66670999839, 7736.574488403192, 7857.960710196641, 7893.644785984759, 8062.274395304451, 8343.624536895733, 8067.475614085066, 9999.450030248407, 10640.414777187329, 10725.785484290114, 10857.619983300547], "value": 9017.105406100687, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.8927285817133, 1462.5758530026294, 1601.9519414417564, 1797.5056859363665, 1833.7707786526685, 7951.204879512046, 8288.917110828897, 8290.544020078893, 8290.751277461686, 8348.791280217998, 7278.053852999103, 7507.774766757006, 7514.098308203023, 7603.315701586856, 8090.757277281677, 1802.9549261268442, 2075.16993202719, 2111.68324751287, 2298.2071185441077, 2299.4366380236784, 1928.7781905080956, 2319.7796209360085, 2330.5571941331177, 2330.9417264568438, 2333.101755824003, 2247.550489902018, 2287.4624463251207, 2290.0, 2295.5064661097863, 2295.988520057397, 2229.9554008919813, 2252.76098146019, 2278.100150440583, 2290.484640955788, 2292.4383526036063, 2300.0, 2303.608386574286, 2322.291701031185, 2324.8721320327377, 2328.8020518255967, 8170.632321545545, 8237.299829514499, 8300.584970751499, 8306.792330191733, 8320.37597180214, 4274.700770946035, 4918.262260660894, 5082.695038297714, 5087.135187018216, 5091.210862316347, 3395.3548825723033, 3499.9475007874903, 3519.8240087995496, 3557.7153827693724, 3562.305350456662, 1042.593388578455, 1062.2458054781114, 1065.2436769893386, 1079.578964203961, 1081.691717860414, 10752.0, 10805.945970270099, 10882.23824332297, 10883.401412922287, 10909.83635245464, 3423.5207071010077, 4355.760433176206, 4377.8248870044845, 4452.154090722753, 4461.420015397977, 8442.704505342406, 8453.027901791373, 8462.746117616482, 8474.0, 8478.788030299293, 4991.201407774739, 4994.101061808909, 4995.375578052744, 4995.425526064549, 5011.09800235961, 4507.4365704286965, 4650.116477869194, 4695.741734204583, 4715.646326525456, 4716.198246298138, 3346.531485592019, 3441.0881116504147, 3456.084137703511, 3588.2823435313044, 3660.5424321959754, 1091.3670071358592, 1093.3549205968516, 1093.3822390349428, 1152.8558930133734, 1160.5415860734963, 716.3051839715572, 835.7116794705817, 860.8622620380709, 887.454215657366, 899.3839220134229, 5059.2916991621205, 5090.847274581768, 5149.897002059938, 5477.561795056466, 5487.451254874481, 724.3263764698776, 851.2764150472173, 887.9955600222087, 915.922146617545, 922.2898368256539, 3267.575215222006, 3595.496630471736, 3611.7110631149444, 3684.9916795346066, 3719.0330514065995, 9729.0, 9962.950185249027, 10062.547185376676, 10066.396016239049, 10087.546060427298, 9907.950460247652, 9909.0, 9982.401055936667, 10036.749081272881, 10096.0, 7545.207753185837, 7636.847263054708, 7702.075750909927, 7761.223877612194, 7775.1447340792465, 4644.0, 5309.973450132725, 5423.457654234545, 5425.83722488326, 5429.375621803385, 9142.314326425412, 9207.815843682954, 9263.76840578991, 9371.812563748823, 9375.437473751597, 9195.356325057182, 9285.071492850931, 9290.349675522919, 9336.0, 9420.340576159602, 6512.185976752906, 6588.472922166311, 6592.571482853599, 6593.736250549924, 6597.60414375139, 6352.460040896391, 7458.664360103917, 7464.850702985801, 7634.6946122154495, 7644.967929329469, 5898.439648233411, 6077.149199112039, 6853.383195512428, 7016.929830701628, 7057.752978645723, 6797.456203503807, 6922.446204303543, 6925.549839260432, 6960.4779641526075, 6998.300169982961, 6520.934790652223, 6623.900641490285, 6624.205095388585, 6628.370304821035, 6628.767993120217, 4324.481062272547, 4445.666575006952, 4486.50648428666, 4600.585947264763, 4793.904121917472, 4383.7808109595035, 4679.672422930362, 4687.296905464208, 4895.583875370491, 4939.530744579403], "value": 4257.545149864819, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.7878606189605, 299.6598860293567, 306.6136667798574, 312.732613615359, 313.510922960182, 5112.156494178456, 5237.45006774289, 5294.470552944708, 5360.410354860967, 5375.220593014015, 4843.730481924696, 5039.72281524516, 5137.049645815527, 5143.485651434864, 5152.355955505562, 3990.122173121923, 4004.859829905954, 4012.678985681153, 4023.6982226333, 4055.9797201014026, 3132.718055375016, 3137.3882092991876, 4002.919941601166, 4043.6967227457912, 4059.269331520325, 3413.368526822546, 3930.5676375598805, 3941.9408708869246, 3952.4861767970124, 3992.141689536749, 3407.7784943978563, 3815.236952609489, 3940.940885886714, 3955.7033222508426, 3958.584348643394, 3064.0960916529675, 3334.71654909333, 3351.765376423651, 3491.1621210909198, 3503.562054743157, 4110.979445102786, 4677.707923117648, 5072.670276432057, 5114.803247970019, 5174.697259719426, 3778.9433158502643, 4004.5194576650697, 4266.488021437447, 4514.9774251128865, 4541.568550987651, 4579.473360563511, 5478.644183001308, 5515.696636684981, 5515.8692468043955, 5532.5573954083575, 3599.1362073102528, 3797.164623782791, 3813.103920578662, 3828.3683192273243, 3841.692664586826, 4821.614270858393, 5707.286589176353, 5728.768314812293, 5734.541236701054, 5738.397468265814, 3469.7495567929086, 3552.751307408457, 3710.461983012448, 3846.769193848378, 3866.284737323604, 3288.67113288674, 3589.2821435712963, 3822.8470861165797, 3886.980565097213, 3908.5309762828642, 3773.471713960048, 4468.865934021985, 4501.212287849678, 4508.864734057984, 4510.789713040652, 4629.976850115795, 5132.615053870974, 5525.557955363562, 5548.639338442988, 5585.860353491195, 1149.620625193684, 1152.795328881321, 1164.4585267850491, 1241.6585439004346, 1251.3055254333933, 750.8873668949592, 836.1513064239801, 838.6393850644281, 860.889452606131, 869.2090197919863, 4657.0, 4752.718695026338, 4909.656324057281, 4957.975210123926, 4962.727050012212, 4942.935380064311, 5079.704675307773, 5394.8921021579345, 5398.838034858961, 5446.366090172933, 5292.44429334922, 5314.0, 5322.281491998608, 5350.759215835296, 5365.1147560652835, 2451.2523680277686, 2825.7707897064843, 2836.163331817119, 2849.3588942487772, 2852.8430936298287, 4344.154578750923, 5258.894822103536, 5576.804811831566, 5611.438856114438, 5630.4369563043365, 5500.614956952975, 5533.833984980457, 5542.584306177053, 5547.805826796042, 5587.469190426903, 4594.1271158479785, 4682.471465408709, 5303.814366497154, 5369.382521010056, 5605.066252143086, 4391.165678521071, 4582.518835522222, 4586.082783443391, 5094.459350751193, 5360.874216414597, 4612.53874612536, 4888.682235654671, 4903.803847846046, 5171.922421163761, 5277.894442111213, 5007.674501157413, 5480.945190548044, 5530.446955304437, 5547.694876781816, 5593.105103183471, 3316.9484074650895, 3523.3305671922253, 3808.676262517717, 3866.497355343787, 3883.6893048556612, 5648.406917273627, 5676.460736230051, 5689.971550142222, 5716.42835716425, 5722.427757224411, 4971.95588926315, 5479.397266300624, 5498.890022199614, 5572.35917869434, 5625.974649126229, 5164.7417629119145, 5416.187571864252, 5440.619156658996, 5472.863178420412, 5492.47821456961, 4484.39460672805, 5092.694438333712, 5152.76812543444, 5262.89474210506, 5284.365876094893, 4856.077345304381, 5015.9968006399595, 5069.5944324454695, 5324.387695415076, 5487.2015272210665, 4318.632916202158, 4473.105378924163, 4548.454185497629, 4792.568668819823, 5410.891782164256], "value": 3921.192139608384, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.6170736948362, 755.8866170074488, 763.3397111498552, 785.5993443343895, 796.1202870827739, 15132.319045642958, 15794.763078553831, 15825.84174158258, 15826.28781704823, 15922.601934951617, 14636.195009274512, 14753.48362807302, 14764.483243086494, 14772.704545909075, 14779.482718104868, 665.4809248785955, 688.2326206280012, 818.3330585572754, 843.5950743643054, 847.1147650704997, 681.9659017049128, 812.0458461307969, 829.7635173975418, 839.1063517353998, 840.865461526156, 881.532787622561, 885.7741275974618, 886.3751055505885, 887.3300658003199, 888.8400087984161, 697.069412334534, 880.7225723896952, 880.7445840706188, 880.8678698195258, 881.7354793561906, 534.260049830983, 534.5002422734756, 535.4297672978305, 535.6036532965617, 626.7117126121989, 13666.0, 13753.862461375358, 14119.576412707635, 14122.082064665763, 14129.010969232158, 12933.547325843645, 13066.608001759867, 13209.207447553177, 13220.933895330558, 13283.402246898817, 10336.741581460523, 12281.877181228163, 12294.323812190327, 12314.199577027464, 12357.876421235762, 4330.220560299145, 4365.80353884076, 4430.0, 4468.955310446887, 4586.449626044896, 92086.99999999933, 92245.53877230638, 93625.12749745035, 95430.52284738602, 96813.51593242059, 9860.654877079267, 10291.897081029243, 10513.52689128991, 10627.787444251226, 10690.478190614014, 20733.170673173205, 20743.58512829765, 20760.792392075888, 20765.58468830615, 20770.961451927342, 7486.0, 7489.13874904393, 7640.197779233101, 7695.807604809925, 7737.187595302525, 4103.8974025649595, 4196.685248606305, 4199.68502362324, 4229.069604686964, 4334.393184954109, 3260.2012506935953, 3315.966840331614, 3326.3846188455213, 3346.832658367072, 3358.580177477815, 627.9246490421178, 638.5944924972675, 642.5823214910346, 643.8068579426154, 648.6691787188473, 524.4467087223017, 603.7072020070262, 614.2168734862996, 629.6064959400375, 631.7915088021034, 7976.322012628992, 8628.22345988864, 9223.216026637676, 9544.570494327772, 9627.759306017406, 497.6566169343142, 647.1942431672504, 668.6924014953177, 696.3523922751788, 708.256330852613, 3556.87550935716, 4502.212112880233, 4522.954770452319, 4540.614047805973, 4642.373279607277, 82540.58729706432, 106024.0, 107968.4601576987, 110765.0, 114126.0, 61775.0, 75206.0, 77973.0, 98247.0, 100737.99262007432, 68938.52291018842, 76377.09057273502, 78937.44781484846, 79915.60042199751, 82501.0, 9252.629894804133, 9673.516324183904, 9811.901880981099, 9858.457784821616, 9863.704088877059, 49265.50734492609, 50945.74527127341, 53119.672008200105, 54723.179152311954, 55554.94650607641, 57151.42848571461, 68260.3173968254, 68941.3105868955, 72258.0, 76249.23750762422, 12536.623901282612, 12646.241225526497, 12737.74524509486, 12806.935965320114, 13115.147515411467, 13093.345332733135, 13640.79538806899, 13726.931365343109, 14252.002359834712, 14633.780493292395, 12217.45021474018, 12499.50445986125, 13319.53381631677, 13447.327633618475, 13700.93149534246, 12943.223406595635, 13010.80483792725, 13113.950883929072, 13177.934110329386, 13449.663758405726, 12173.573924912585, 12187.268763874195, 12486.188397754117, 12621.0, 13131.803022954473, 10401.81819994216, 10602.734931626772, 10733.034026937614, 11161.776764464828, 11179.441027948733, 10708.89291107079, 10874.565017399216, 11206.439678015904, 11264.60573879943, 11539.8269025963], "value": 7260.705678986899, "unit": "score"}], "name": "dromaeo_css", "value": 6945.5284633380215, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 113.0, 115.0, 115.0, 113.0, 114.0, 114.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 115.0, 176.0, 139.0, 127.0, 110.0, 141.0, 175.0, 138.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 183.0, 185.0, 183.0, 182.0, 184.0, 182.0, 185.0, 183.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 60.0, 90.0, 62.0, 59.0, 59.0, 60.0, 100.0, 82.0], "value": 71.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 95.0, 70.0, 68.0, 69.0, 88.0, 68.0, 69.0, 92.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 131.0, 87.0, 85.0, 87.0, 83.0, 87.0, 85.0, 86.0, 84.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 108.0, 149.0, 127.0, 93.0, 94.0, 92.0, 93.0, 93.0, 92.0], "value": 103.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 79.0, 81.0, 84.0, 82.0, 84.0, 85.0, 81.0, 80.0], "value": 81.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 53.0, 52.0, 57.0, 58.0, 59.0, 57.0, 56.0, 57.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 68.0, 76.0, 71.0, 69.0, 69.0, 70.0, 71.0, 72.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 84.0, 84.0, 81.0, 83.0, 83.0, 82.0, 83.0, 96.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 141.0, 146.0, 153.0, 154.0, 162.0, 145.0, 183.0, 169.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 165.0, 166.0, 166.0, 165.0, 163.0, 166.0, 163.0, 164.0], "value": 164.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 69.0, 66.0, 68.0, 68.0, 69.0, 80.0, 79.0], "value": 70.2, "unit": "score"}], "name": "kraken", "value": 1458.9, "alertThreshold": 2.0}]} 05:43:58 INFO - Return code: 0 05:43:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:43:58 INFO - # TBPL SUCCESS # 05:43:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:43:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:43:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:43:58 INFO - 2016-12-15 05:43:58.197 screenresolution[1659:9906] starting screenresolution argv=screenresolution get 05:43:58 INFO - 2016-12-15 05:43:58.200 screenresolution[1659:9906] Display 0: 1600x1200x32@60 05:43:58 INFO - 2016-12-15 05:43:58.207 screenresolution[1660:9910] starting screenresolution argv=screenresolution list 05:43:58 INFO - Available Modes on Display 0 05:43:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:43:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:43:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:43:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:43:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:43:58 INFO - Intel Iris: 05:43:58 INFO - Chipset Model: Intel Iris 05:43:58 INFO - Type: GPU 05:43:58 INFO - Bus: Built-In 05:43:58 INFO - VRAM (Dynamic, Max): 1536 MB 05:43:58 INFO - Vendor: Intel (0x8086) 05:43:58 INFO - Device ID: 0x0a2e 05:43:58 INFO - Revision ID: 0x0009 05:43:58 INFO - Displays: 05:43:58 INFO - Display: 05:43:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:43:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:43:58 INFO - Display Serial Number: 1600x1200 60 05:43:58 INFO - Main Display: Yes 05:43:58 INFO - Mirror: Off 05:43:58 INFO - Online: Yes 05:43:58 INFO - Rotation: Supported 05:43:58 INFO - Return code: 0 05:43:58 INFO - Running post-action listener: _resource_record_post_action 05:43:58 INFO - [mozharness: 2016-12-15 13:43:58.454840Z] Finished run-tests step (success) 05:43:58 INFO - Running post-run listener: _resource_record_post_run 05:43:58 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 33545216; Write bytes: 660131840; Read time: 315; Write time: 2132 05:43:58 INFO - TinderboxPrint: CPU usage
53.4% 05:43:58 INFO - TinderboxPrint: I/O read bytes / time
33,545,216 / 315 05:43:58 INFO - TinderboxPrint: I/O write bytes / time
660,131,840 / 2,132 05:43:58 INFO - TinderboxPrint: CPU idle
2,257.5 (46.5%) 05:43:58 INFO - TinderboxPrint: CPU system
974.0 (20.1%) 05:43:58 INFO - TinderboxPrint: CPU user
1,618.2 (33.4%) 05:43:58 INFO - TinderboxPrint: Swap in / out
378,060,800 / 0 05:43:58 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 347961856; Write bytes: 345315328; Read time: 26815; Write time: 678 05:43:58 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 29113344; Write bytes: 307468288; Read time: 280; Write time: 1425 05:43:58 INFO - Running post-run listener: _upload_blobber_files 05:43:58 INFO - Blob upload gear active. 05:43:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:43:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:43:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:43:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:43:59 INFO - (blobuploader) - INFO - Open directory for files ... 05:43:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:43:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:44:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:00 INFO - (blobuploader) - INFO - Done attempting. 05:44:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:44:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:44:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:01 INFO - (blobuploader) - INFO - Done attempting. 05:44:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:44:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:44:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:03 INFO - (blobuploader) - INFO - Done attempting. 05:44:03 INFO - (blobuploader) - INFO - Iteration through files over. 05:44:03 INFO - Return code: 0 05:44:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:44:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:44:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e88554f9363d2a8132753bcc908c54c3e55b298f48da5f4c4be3c37607c1165cecb095b9f5c20a90969d1a5cf438168c72cf3f53fdb21b78896c9ea095d0e6f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8106b961c791f33504b1784520b068dfeaf0f767cedde204715af34b3e6c8a7b54827a1b7b9b8b10b2eea7f51e4aece42b92cb70b5802007f420eb147f05431a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dea1fffd40f803150408b4f3501e946d764e7e78833fd0317f08f2f702b683a83db087b1137e7abd1d19950e2386cc1a1a1b649b2999da73265b6226b42221cf"} 05:44:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:44:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:44:03 INFO - Contents: 05:44:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e88554f9363d2a8132753bcc908c54c3e55b298f48da5f4c4be3c37607c1165cecb095b9f5c20a90969d1a5cf438168c72cf3f53fdb21b78896c9ea095d0e6f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8106b961c791f33504b1784520b068dfeaf0f767cedde204715af34b3e6c8a7b54827a1b7b9b8b10b2eea7f51e4aece42b92cb70b5802007f420eb147f05431a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dea1fffd40f803150408b4f3501e946d764e7e78833fd0317f08f2f702b683a83db087b1137e7abd1d19950e2386cc1a1a1b649b2999da73265b6226b42221cf"} 05:44:03 INFO - Running post-run listener: copy_logs_to_upload_dir 05:44:03 INFO - Copying logs to upload dir... 05:44:03 INFO - mkdir: /builds/slave/test/build/upload/logs 05:44:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.172552 ========= master_lag: 1.33 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-15 05:44:04.898177) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 05:44:04.901471) ========= 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.IvuR13GsjK/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.VikbbSClrF/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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/e88554f9363d2a8132753bcc908c54c3e55b298f48da5f4c4be3c37607c1165cecb095b9f5c20a90969d1a5cf438168c72cf3f53fdb21b78896c9ea095d0e6f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8106b961c791f33504b1784520b068dfeaf0f767cedde204715af34b3e6c8a7b54827a1b7b9b8b10b2eea7f51e4aece42b92cb70b5802007f420eb147f05431a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dea1fffd40f803150408b4f3501e946d764e7e78833fd0317f08f2f702b683a83db087b1137e7abd1d19950e2386cc1a1a1b649b2999da73265b6226b42221cf"} build_url:https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011282 build_url: 'https://queue.taskcluster.net/v1/task/GIXVR7P0Qz6dfmzohJmipA/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/e88554f9363d2a8132753bcc908c54c3e55b298f48da5f4c4be3c37607c1165cecb095b9f5c20a90969d1a5cf438168c72cf3f53fdb21b78896c9ea095d0e6f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8106b961c791f33504b1784520b068dfeaf0f767cedde204715af34b3e6c8a7b54827a1b7b9b8b10b2eea7f51e4aece42b92cb70b5802007f420eb147f05431a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dea1fffd40f803150408b4f3501e946d764e7e78833fd0317f08f2f702b683a83db087b1137e7abd1d19950e2386cc1a1a1b649b2999da73265b6226b42221cf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 05:44:04.935836) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 05:44:04.936141) ========= 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.IvuR13GsjK/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.VikbbSClrF/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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.005145 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 05:44:04.985083) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 05:44:04.985424) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 05:44:04.985755) ========= ========= Total master_lag: 1.48 =========