builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0352 starttime: 1480486990.39 results: success (0) buildid: 20161129195959 builduid: 8ab0850f85dc472a83277bb799eb1e38 revision: 96503957841c8c7617a416719c89a06778de396a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:10.393259) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:10.393766) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:10.394068) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:10.423060) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:10.423357) ========= 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.lyrXMDEQxl/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.Z03uutpdnm/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-29 22:23:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 61.8M=0s 2016-11-29 22:23:10 (61.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.094659 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:10.545035) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:10.545360) ========= 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.lyrXMDEQxl/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.Z03uutpdnm/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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.025168 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:10.597104) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 22:23:10.597471) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 96503957841c8c7617a416719c89a06778de396a --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 96503957841c8c7617a416719c89a06778de396a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lyrXMDEQxl/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.Z03uutpdnm/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-29 22:23:10,698 truncating revision to first 12 chars 2016-11-29 22:23:10,698 Setting DEBUG logging. 2016-11-29 22:23:10,698 attempt 1/10 2016-11-29 22:23:10,698 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/96503957841c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 22:23:12,393 unpacking tar archive at: mozilla-aurora-96503957841c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.958997 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 22:23:12.580418) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:12.580750) ========= script_repo_revision: 96503957841c8c7617a416719c89a06778de396a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:12.581171) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:12.581446) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:12.601182) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-29 22:23:12.601492) ========= /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.lyrXMDEQxl/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.Z03uutpdnm/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 22:23:12 INFO - MultiFileLogger online at 20161129 22:23:12 in /builds/slave/test 22:23:12 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 22:23:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:23:12 INFO - {'append_to_log': False, 22:23:12 INFO - 'base_work_dir': '/builds/slave/test', 22:23:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:23:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:23:12 INFO - 'branch': 'Mozilla-Aurora', 22:23:12 INFO - 'buildbot_json_path': 'buildprops.json', 22:23:12 INFO - 'config_files': ('talos/mac_config.py',), 22:23:12 INFO - 'default_actions': ('clobber', 22:23:12 INFO - 'read-buildbot-config', 22:23:12 INFO - 'download-and-extract', 22:23:12 INFO - 'populate-webroot', 22:23:12 INFO - 'create-virtualenv', 22:23:12 INFO - 'install', 22:23:12 INFO - 'run-tests'), 22:23:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:23:12 INFO - 'download_minidump_stackwalk': True, 22:23:12 INFO - 'download_symbols': 'ondemand', 22:23:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:23:12 INFO - 'tooltool.py': '/tools/tooltool.py', 22:23:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:23:12 INFO - '/tools/misc-python/virtualenv.py')}, 22:23:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:23:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:23:12 INFO - 'installer_path': 'installer.exe', 22:23:12 INFO - 'log_level': 'info', 22:23:12 INFO - 'log_name': 'talos', 22:23:12 INFO - 'log_to_console': True, 22:23:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:23:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:23:12 INFO - 'opt_config_files': (), 22:23:12 INFO - 'pip_index': False, 22:23:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:23:12 INFO - 'cmd': ('bash', 22:23:12 INFO - '-c', 22:23:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:23:12 INFO - 'enabled': True, 22:23:12 INFO - 'halt_on_failure': False, 22:23:12 INFO - 'name': 'check_screen_resolution'},), 22:23:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:23:12 INFO - 'cmd': ('bash', 22:23:12 INFO - '-c', 22:23:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:23:12 INFO - 'enabled': True, 22:23:12 INFO - 'halt_on_failure': False, 22:23:12 INFO - 'name': 'check_screen_resolution'},), 22:23:12 INFO - 'run_cmd_checks_enabled': True, 22:23:12 INFO - 'sps_profile': False, 22:23:12 INFO - 'sps_profile_interval': 0, 22:23:12 INFO - 'suite': 'dromaeojs', 22:23:12 INFO - 'system_bits': '32', 22:23:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:23:12 INFO - 'title': 't-yosemite-r7-0352', 22:23:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:23:12 INFO - 'use_talos_json': True, 22:23:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:23:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:23:12 INFO - 'work_dir': 'build'} 22:23:12 INFO - [mozharness: 2016-11-30 06:23:12.809269Z] Running clobber step. 22:23:12 INFO - Running pre-action listener: _resource_record_pre_action 22:23:12 INFO - Running main action method: clobber 22:23:12 INFO - rmtree: /builds/slave/test/build 22:23:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:23:15 INFO - Running post-action listener: _resource_record_post_action 22:23:15 INFO - [mozharness: 2016-11-30 06:23:15.102595Z] Finished clobber step (success) 22:23:15 INFO - [mozharness: 2016-11-30 06:23:15.102756Z] Running read-buildbot-config step. 22:23:15 INFO - Running pre-action listener: _resource_record_pre_action 22:23:15 INFO - Running main action method: read_buildbot_config 22:23:15 INFO - Using buildbot properties: 22:23:15 INFO - { 22:23:15 INFO - "project": "", 22:23:15 INFO - "product": "firefox", 22:23:15 INFO - "script_repo_revision": "production", 22:23:15 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 22:23:15 INFO - "repository": "", 22:23:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 22:23:15 INFO - "buildid": "20161129195959", 22:23:15 INFO - "pgo_build": "False", 22:23:15 INFO - "basedir": "/builds/slave/test", 22:23:15 INFO - "buildnumber": 13, 22:23:15 INFO - "slavename": "t-yosemite-r7-0352", 22:23:15 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 22:23:15 INFO - "platform": "yosemite_r7", 22:23:15 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 22:23:15 INFO - "branch": "mozilla-aurora", 22:23:15 INFO - "repo_path": "releases/mozilla-aurora", 22:23:15 INFO - "revision": "96503957841c8c7617a416719c89a06778de396a", 22:23:15 INFO - "stage_platform": "macosx64", 22:23:15 INFO - "builduid": "8ab0850f85dc472a83277bb799eb1e38", 22:23:15 INFO - "slavebuilddir": "test" 22:23:15 INFO - } 22:23:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 22:23:15 INFO - Running post-action listener: _resource_record_post_action 22:23:15 INFO - [mozharness: 2016-11-30 06:23:15.105973Z] Finished read-buildbot-config step (success) 22:23:15 INFO - [mozharness: 2016-11-30 06:23:15.106103Z] Running download-and-extract step. 22:23:15 INFO - Running pre-action listener: _resource_record_pre_action 22:23:15 INFO - Running main action method: download_and_extract 22:23:15 INFO - mkdir: /builds/slave/test/build/tests 22:23:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:15 INFO - trying https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 22:23:15 INFO - Downloading https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/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 22:23:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/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 22:23:15 INFO - Downloaded 1199 bytes. 22:23:15 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 22:23:15 INFO - Using the following test package requirements: 22:23:15 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 22:23:15 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:15 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 22:23:15 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:15 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 22:23:15 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:15 INFO - u'jsshell-mac.zip'], 22:23:15 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:15 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 22:23:15 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 22:23:15 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:15 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 22:23:15 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:15 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 22:23:15 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:15 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 22:23:15 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:15 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 22:23:15 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 22:23:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 22:23:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:23:15 INFO - Fetch https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 22:23:16 INFO - Http code: 200 22:23:16 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 22:23:16 INFO - x-amz-cf-id: 19tm36OcE0IGJfNx37FBjzifEdE3-vU1yC-VTxyAuztrJ3b-hGVa5w== 22:23:16 INFO - x-amz-version-id: KH8RTMEoNzOQfBh29dxyKIIMRgP.fvst 22:23:16 INFO - Expected file size: 16285135 22:23:16 INFO - Obtained file size: 16285135 22:23:23 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 22:23:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 22:23:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:23:23 INFO - Fetch https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 22:23:23 INFO - Http code: 200 22:23:23 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 22:23:23 INFO - x-amz-cf-id: wvX2IS1_kO0YoCeCbgxR897OBHL2nUtb4pc3ScMcfzufYweKnBGfIw== 22:23:23 INFO - x-amz-version-id: KH8RTMEoNzOQfBh29dxyKIIMRgP.fvst 22:23:24 INFO - Expected file size: 16285135 22:23:24 INFO - Obtained file size: 16285135 22:23:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 22:23:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 22:23:30 INFO - Fetch https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 22:23:31 INFO - Http code: 200 22:23:31 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 22:23:31 INFO - x-amz-cf-id: GFWhEKPn80dwhJCZhhDMFMvgE8m912P1G7IHV-jThZuH--78bvTjIQ== 22:23:31 INFO - x-amz-version-id: q.A7Vhr.U0WcK0jZeTfVlaTpZKatDnjE 22:23:31 INFO - Expected file size: 14409038 22:23:31 INFO - Obtained file size: 14409038 22:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:31 INFO - trying https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:23:31 INFO - Downloading https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 22:23:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/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 22:23:34 INFO - Downloaded 109716068 bytes. 22:23:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:23:34 INFO - mkdir: /builds/slave/test/properties 22:23:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:23:34 INFO - Writing to file /builds/slave/test/properties/build_url 22:23:34 INFO - Contents: 22:23:34 INFO - build_url:https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:23:35 INFO - Running post-action listener: _resource_record_post_action 22:23:35 INFO - Running post-action listener: set_extra_try_arguments 22:23:35 INFO - [mozharness: 2016-11-30 06:23:35.168436Z] Finished download-and-extract step (success) 22:23:35 INFO - [mozharness: 2016-11-30 06:23:35.168575Z] Running populate-webroot step. 22:23:35 INFO - Running pre-action listener: _resource_record_pre_action 22:23:35 INFO - Running main action method: populate_webroot 22:23:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:23:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:23:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:23:35 INFO - '--tppagecycles', 22:23:35 INFO - '7'], 22:23:35 INFO - 'tests': ['tsvgm']}}, 22:23:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:23:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:23:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:23:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:23:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:23:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:23:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:35 INFO - 'talos_options': ['--disable-e10s'], 22:23:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:35 INFO - 'talos_options': ['--disable-e10s'], 22:23:35 INFO - 'tests': ['damp', 'tps']}, 22:23:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:35 INFO - 'tests': ['damp', 'tps']}, 22:23:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:23:35 INFO - 'tests': ['dromaeo_dom']}, 22:23:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:23:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:23:35 INFO - 'tests': ['basic_compositor_video']}, 22:23:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:23:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:23:35 INFO - 'tests': ['a11yr', 22:23:35 INFO - 'ts_paint', 22:23:35 INFO - 'tpaint', 22:23:35 INFO - 'sessionrestore', 22:23:35 INFO - 'sessionrestore_no_auto_restore', 22:23:35 INFO - 'tabpaint']}, 22:23:35 INFO - 'other-e10s': {'tests': ['a11yr', 22:23:35 INFO - 'ts_paint', 22:23:35 INFO - 'tpaint', 22:23:35 INFO - 'sessionrestore', 22:23:35 INFO - 'sessionrestore_no_auto_restore', 22:23:35 INFO - 'tabpaint']}, 22:23:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:23:35 INFO - 'ts_paint', 22:23:35 INFO - 'tpaint', 22:23:35 INFO - 'sessionrestore', 22:23:35 INFO - 'sessionrestore_no_auto_restore', 22:23:35 INFO - 'tabpaint']}, 22:23:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:23:35 INFO - 'ts_paint', 22:23:35 INFO - 'tpaint', 22:23:35 INFO - 'sessionrestore', 22:23:35 INFO - 'sessionrestore_no_auto_restore', 22:23:35 INFO - 'tabpaint']}, 22:23:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:23:35 INFO - 'tests': ['a11yr', 22:23:35 INFO - 'ts_paint', 22:23:35 INFO - 'tpaint', 22:23:35 INFO - 'sessionrestore', 22:23:35 INFO - 'sessionrestore_no_auto_restore', 22:23:35 INFO - 'tabpaint']}, 22:23:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:23:35 INFO - 'tests': ['a11yr', 22:23:35 INFO - 'ts_paint', 22:23:35 INFO - 'tpaint', 22:23:35 INFO - 'sessionrestore', 22:23:35 INFO - 'sessionrestore_no_auto_restore', 22:23:35 INFO - 'tabpaint']}, 22:23:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:23:35 INFO - 'tests': ['tsvgx', 22:23:35 INFO - 'tsvgr_opacity', 22:23:35 INFO - 'tart', 22:23:35 INFO - 'tscrollx', 22:23:35 INFO - 'cart']}, 22:23:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:23:35 INFO - 'tsvgr_opacity', 22:23:35 INFO - 'tart', 22:23:35 INFO - 'tscrollx', 22:23:35 INFO - 'cart']}, 22:23:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:35 INFO - 'talos_options': ['--disable-e10s'], 22:23:35 INFO - 'tests': ['tp5o']}, 22:23:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:35 INFO - 'tests': ['tp5o']}, 22:23:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:35 INFO - 'talos_options': ['--disable-e10s', 22:23:35 INFO - '--xperf_path', 22:23:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:35 INFO - 'tests': ['tp5n']}, 22:23:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:35 INFO - 'talos_options': ['--xperf_path', 22:23:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:35 INFO - 'tests': ['tp5n']}}, 22:23:35 INFO - 'talos.zip': {'path': '', 22:23:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:23:35 INFO - Running post-action listener: _resource_record_post_action 22:23:35 INFO - [mozharness: 2016-11-30 06:23:35.187388Z] Finished populate-webroot step (success) 22:23:35 INFO - [mozharness: 2016-11-30 06:23:35.187499Z] Running create-virtualenv step. 22:23:35 INFO - Running pre-action listener: _resource_record_pre_action 22:23:35 INFO - Running main action method: create_virtualenv 22:23:35 INFO - Creating virtualenv /builds/slave/test/build/venv 22:23:35 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 22:23:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:23:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:23:35 INFO - Using real prefix '/tools/python27' 22:23:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:23:36 INFO - Installing distribute.............................................................................................................................................................................................done. 22:23:38 INFO - Installing pip.................done. 22:23:38 INFO - Return code: 0 22:23:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:23:39 INFO - Reading from file tmpfile_stdout 22:23:39 INFO - Output received: 22:23:39 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) 22:23:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22: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')]} 22:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22: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')]} 22:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:39 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 0x1055a5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5c95c4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053b9d30>, '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 0x10521fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b1b20>, '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.lyrXMDEQxl/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.Z03uutpdnm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 22:23:39 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 22:23:39 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 22:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lyrXMDEQxl/Render', 22:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:39 INFO - 'HOME': '/Users/cltbld', 22:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:39 INFO - 'LOGNAME': 'cltbld', 22:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:39 INFO - 'MOZ_NO_REMOTE': '1', 22:23:39 INFO - 'NO_EM_RESTART': '1', 22:23:39 INFO - 'PAGER': '/bin/cat', 22:23:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:39 INFO - 'PWD': '/builds/slave/test', 22:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:39 INFO - 'SHELL': '/bin/bash', 22:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z03uutpdnm/Listeners', 22:23:39 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 22:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:39 INFO - 'USER': 'cltbld', 22:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:39 INFO - 'XPC_FLAGS': '0x0', 22:23:39 INFO - 'XPC_SERVICE_NAME': '0', 22:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:39 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 22:23:39 INFO - Cleaning up... 22:23:39 INFO - Return code: 0 22:23:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22: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')]} 22:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22: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')]} 22:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:39 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 0x1055a5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5c95c4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053b9d30>, '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 0x10521fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b1b20>, '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.lyrXMDEQxl/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.Z03uutpdnm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 22:23:39 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 22:23:39 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 22:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lyrXMDEQxl/Render', 22:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:39 INFO - 'HOME': '/Users/cltbld', 22:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:39 INFO - 'LOGNAME': 'cltbld', 22:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:39 INFO - 'MOZ_NO_REMOTE': '1', 22:23:39 INFO - 'NO_EM_RESTART': '1', 22:23:39 INFO - 'PAGER': '/bin/cat', 22:23:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:39 INFO - 'PWD': '/builds/slave/test', 22:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:39 INFO - 'SHELL': '/bin/bash', 22:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z03uutpdnm/Listeners', 22:23:39 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 22:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:39 INFO - 'USER': 'cltbld', 22:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:39 INFO - 'XPC_FLAGS': '0x0', 22:23:39 INFO - 'XPC_SERVICE_NAME': '0', 22:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:39 INFO - Downloading/unpacking psutil>=3.1.1 22:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:23:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:23:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:23:43 INFO - Installing collected packages: psutil 22:23:43 INFO - Running setup.py install for psutil 22:23:43 INFO - building 'psutil._psutil_osx' extension 22:23:43 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 22:23:43 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 22:23:43 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 22:23:44 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 22:23:44 INFO - building 'psutil._psutil_posix' extension 22:23:44 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 22:23:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:23:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:23:44 INFO - ^ 22:23:44 INFO - 1 warning generated. 22:23:44 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 22:23:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:23:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:23:44 INFO - Successfully installed psutil 22:23:44 INFO - Cleaning up... 22:23:44 INFO - Return code: 0 22:23:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:23:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1055a5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5c95c4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053b9d30>, '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 0x10521fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b1b20>, '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.lyrXMDEQxl/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.Z03uutpdnm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 22:23:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:23:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lyrXMDEQxl/Render', 22:23:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:44 INFO - 'HOME': '/Users/cltbld', 22:23:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:44 INFO - 'LOGNAME': 'cltbld', 22:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:44 INFO - 'MOZ_NO_REMOTE': '1', 22:23:44 INFO - 'NO_EM_RESTART': '1', 22:23:44 INFO - 'PAGER': '/bin/cat', 22:23:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:44 INFO - 'PWD': '/builds/slave/test', 22:23:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:44 INFO - 'SHELL': '/bin/bash', 22:23:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z03uutpdnm/Listeners', 22:23:44 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 22:23:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:44 INFO - 'USER': 'cltbld', 22:23:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:44 INFO - 'XPC_FLAGS': '0x0', 22:23:44 INFO - 'XPC_SERVICE_NAME': '0', 22:23:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:23:44 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 22:23:44 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 22:23:44 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 22:23:44 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 22:23:44 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 22:23:44 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 22:23:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:23:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:23:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:23:47 INFO - Installing collected packages: mozsystemmonitor 22:23:47 INFO - Running setup.py install for mozsystemmonitor 22:23:47 INFO - Successfully installed mozsystemmonitor 22:23:47 INFO - Cleaning up... 22:23:47 INFO - Return code: 0 22:23:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:23:47 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')]} 22:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:47 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')]} 22:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:47 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 0x1055a5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5c95c4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053b9d30>, '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 0x10521fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b1b20>, '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.lyrXMDEQxl/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.Z03uutpdnm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 22:23:47 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 22:23:47 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 22:23:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lyrXMDEQxl/Render', 22:23:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:47 INFO - 'HOME': '/Users/cltbld', 22:23:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:47 INFO - 'LOGNAME': 'cltbld', 22:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:47 INFO - 'MOZ_NO_REMOTE': '1', 22:23:47 INFO - 'NO_EM_RESTART': '1', 22:23:47 INFO - 'PAGER': '/bin/cat', 22:23:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:47 INFO - 'PWD': '/builds/slave/test', 22:23:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:47 INFO - 'SHELL': '/bin/bash', 22:23:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z03uutpdnm/Listeners', 22:23:47 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 22:23:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:47 INFO - 'USER': 'cltbld', 22:23:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:47 INFO - 'XPC_FLAGS': '0x0', 22:23:47 INFO - 'XPC_SERVICE_NAME': '0', 22:23:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:47 INFO - Downloading/unpacking jsonschema==2.5.1 22:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:23:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:50 INFO - Downloading functools32-3.2.3-2.tar.gz 22:23:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:23:50 INFO - warning: no files found matching '*.txt' 22:23:50 INFO - no previously-included directories found matching 'build' 22:23:50 INFO - no previously-included directories found matching 'dist' 22:23:50 INFO - no previously-included directories found matching '.git*' 22:23:51 INFO - Installing collected packages: jsonschema, functools32 22:23:51 INFO - Running setup.py install for functools32 22:23:51 INFO - warning: no files found matching '*.txt' 22:23:51 INFO - no previously-included directories found matching 'build' 22:23:51 INFO - no previously-included directories found matching 'dist' 22:23:51 INFO - no previously-included directories found matching '.git*' 22:23:51 INFO - Successfully installed jsonschema functools32 22:23:51 INFO - Cleaning up... 22:23:51 INFO - Return code: 0 22:23:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:23: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')]} 22:23:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23: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')]} 22:23:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1055a5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5c95c4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053b9d30>, '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 0x10521fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b1b20>, '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.lyrXMDEQxl/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.Z03uutpdnm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 22:23:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:23:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:23:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lyrXMDEQxl/Render', 22:23:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:51 INFO - 'HOME': '/Users/cltbld', 22:23:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:51 INFO - 'LOGNAME': 'cltbld', 22:23:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:51 INFO - 'MOZ_NO_REMOTE': '1', 22:23:51 INFO - 'NO_EM_RESTART': '1', 22:23:51 INFO - 'PAGER': '/bin/cat', 22:23:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:51 INFO - 'PWD': '/builds/slave/test', 22:23:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:51 INFO - 'SHELL': '/bin/bash', 22:23:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z03uutpdnm/Listeners', 22:23:51 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 22:23:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:51 INFO - 'USER': 'cltbld', 22:23:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:51 INFO - 'XPC_FLAGS': '0x0', 22:23:51 INFO - 'XPC_SERVICE_NAME': '0', 22:23:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:23:51 INFO - Cleaning up... 22:23:51 INFO - Return code: 0 22:23:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:23: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')]} 22:23:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23: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')]} 22:23:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1055a5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5c95c4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053b9d30>, '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 0x10521fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b1b20>, '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.lyrXMDEQxl/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.Z03uutpdnm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 22:23:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:23:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:23:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lyrXMDEQxl/Render', 22:23:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:51 INFO - 'HOME': '/Users/cltbld', 22:23:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:51 INFO - 'LOGNAME': 'cltbld', 22:23:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:51 INFO - 'MOZ_NO_REMOTE': '1', 22:23:51 INFO - 'NO_EM_RESTART': '1', 22:23:51 INFO - 'PAGER': '/bin/cat', 22:23:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:51 INFO - 'PWD': '/builds/slave/test', 22:23:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:51 INFO - 'SHELL': '/bin/bash', 22:23:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z03uutpdnm/Listeners', 22:23:51 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 22:23:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:51 INFO - 'USER': 'cltbld', 22:23:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:51 INFO - 'XPC_FLAGS': '0x0', 22:23:51 INFO - 'XPC_SERVICE_NAME': '0', 22:23:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:51 INFO - Downloading/unpacking blobuploader==1.2.4 22:23:51 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 22:23:51 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 22:23:51 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 22:23:51 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 22:23:51 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 22:23:51 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 22:23:54 INFO - Downloading blobuploader-1.2.4.tar.gz 22:23:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:23:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:23:54 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 22:23:54 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 22:23:54 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 22:23:54 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 22:23:54 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 22:23:54 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 22:23:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:23:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:23:55 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 22:23:55 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 22:23:55 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 22:23:55 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 22:23:55 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 22:23:55 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 22:23:55 INFO - Downloading docopt-0.6.1.tar.gz 22:23:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:23:55 INFO - Installing collected packages: blobuploader, requests, docopt 22:23:55 INFO - Running setup.py install for blobuploader 22:23:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:23:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:23:55 INFO - Running setup.py install for requests 22:23:56 INFO - Running setup.py install for docopt 22:23:56 INFO - Successfully installed blobuploader requests docopt 22:23:56 INFO - Cleaning up... 22:23:56 INFO - Return code: 0 22:23:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23:56 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')]} 22:23:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:56 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')]} 22:23:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:56 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 0x1055a5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5c95c4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053b9d30>, '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 0x10521fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b1b20>, '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.lyrXMDEQxl/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.Z03uutpdnm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 22:23:56 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 22:23:56 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 22:23:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lyrXMDEQxl/Render', 22:23:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:56 INFO - 'HOME': '/Users/cltbld', 22:23:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:56 INFO - 'LOGNAME': 'cltbld', 22:23:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:56 INFO - 'MOZ_NO_REMOTE': '1', 22:23:56 INFO - 'NO_EM_RESTART': '1', 22:23:56 INFO - 'PAGER': '/bin/cat', 22:23:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:56 INFO - 'PWD': '/builds/slave/test', 22:23:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:56 INFO - 'SHELL': '/bin/bash', 22:23:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z03uutpdnm/Listeners', 22:23:56 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 22:23:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:56 INFO - 'USER': 'cltbld', 22:23:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:56 INFO - 'XPC_FLAGS': '0x0', 22:23:56 INFO - 'XPC_SERVICE_NAME': '0', 22:23:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:23:56 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-9Ktzye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:23:57 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-UEWzGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:23:57 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-SggHio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:23:57 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-SafvMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:23:57 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-wbRovW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:23:57 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-QR6xuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:23:57 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-6ZLGeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:23:57 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-fLrUxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:23:57 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-GLokbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:23:57 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-cts9Ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:23:57 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-5vq04g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:23:58 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-QVp2yg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:23:58 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-Py34jt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:23:58 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-EQusf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:58 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-K9wK7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:23:58 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-PkbE61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:23:58 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-Xt_dyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:23:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:23:58 INFO - Running setup.py install for manifestparser 22:23:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:23:58 INFO - Running setup.py install for mozcrash 22:23:58 INFO - Running setup.py install for mozdebug 22:23:59 INFO - Running setup.py install for mozdevice 22:23:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:23:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:23:59 INFO - Running setup.py install for mozfile 22:23:59 INFO - Running setup.py install for mozhttpd 22:23:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:23:59 INFO - Running setup.py install for mozinfo 22:23:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:23:59 INFO - Running setup.py install for mozInstall 22:23:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:23:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:23:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:23:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:23:59 INFO - Running setup.py install for mozleak 22:23:59 INFO - Running setup.py install for mozlog 22:24:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:24:00 INFO - Running setup.py install for moznetwork 22:24:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:24:00 INFO - Running setup.py install for mozprocess 22:24:00 INFO - Running setup.py install for mozprofile 22:24:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:24:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:24:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:24:00 INFO - Running setup.py install for mozrunner 22:24:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:24:00 INFO - Running setup.py install for mozscreenshot 22:24:00 INFO - Running setup.py install for moztest 22:24:01 INFO - Running setup.py install for mozversion 22:24:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:24:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:24:01 INFO - Cleaning up... 22:24:01 INFO - Return code: 0 22:24:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:01 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')]} 22:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:01 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')]} 22:24:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:01 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 0x1055a5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5c95c4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053b9d30>, '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 0x10521fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b1b20>, '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.lyrXMDEQxl/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.Z03uutpdnm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 22:24:01 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 22:24:01 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 22:24:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lyrXMDEQxl/Render', 22:24:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:01 INFO - 'HOME': '/Users/cltbld', 22:24:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:01 INFO - 'LOGNAME': 'cltbld', 22:24:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:01 INFO - 'MOZ_NO_REMOTE': '1', 22:24:01 INFO - 'NO_EM_RESTART': '1', 22:24:01 INFO - 'PAGER': '/bin/cat', 22:24:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:01 INFO - 'PWD': '/builds/slave/test', 22:24:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:01 INFO - 'SHELL': '/bin/bash', 22:24:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z03uutpdnm/Listeners', 22:24:01 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 22:24:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:01 INFO - 'USER': 'cltbld', 22:24:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:01 INFO - 'XPC_FLAGS': '0x0', 22:24:01 INFO - 'XPC_SERVICE_NAME': '0', 22:24:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:24:01 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-_nU46E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:24:01 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)) 22:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:24:01 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-4tGQHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:24:01 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)) 22:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:24:01 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-1r3V9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:24:01 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)) 22:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:24:01 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-tk9cet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:24:01 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)) 22:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:24:01 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-6B71ro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:24:02 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)) 22:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:24:02 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-Azi2va-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:24:02 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)) 22:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:24:02 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-utFXgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:24:02 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)) 22:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:24:02 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-R8Fprn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:24:02 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)) 22:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:24:02 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-lX8_cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:24:02 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)) 22:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:24:02 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-xAiaj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:24:02 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)) 22:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:24:02 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-XXJ6WJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:24:02 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)) 22:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:24:02 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-TxRhJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:24:02 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)) 22:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:24:02 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-Z1Q1TU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:24:02 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)) 22:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:24:02 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-OixVBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:24:03 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)) 22:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:03 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-zmdQaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:03 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)) 22:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:24:03 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-vH3Q1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:24:03 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)) 22:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:24:03 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-GjnfpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:24:03 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)) 22:24:03 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)) 22:24:03 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)) 22:24:03 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)) 22:24:03 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)) 22:24:03 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)) 22:24:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:06 INFO - Downloading blessings-1.6.tar.gz 22:24:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:24:06 INFO - Installing collected packages: blessings 22:24:06 INFO - Running setup.py install for blessings 22:24:06 INFO - Successfully installed blessings 22:24:06 INFO - Cleaning up... 22:24:06 INFO - Return code: 0 22:24:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:24:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:24:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:24:06 INFO - Reading from file tmpfile_stdout 22:24:06 INFO - Current package versions: 22:24:06 INFO - blessings == 1.6 22:24:06 INFO - blobuploader == 1.2.4 22:24:06 INFO - docopt == 0.6.1 22:24:06 INFO - functools32 == 3.2.3-2 22:24:06 INFO - jsonschema == 2.5.1 22:24:06 INFO - manifestparser == 1.1 22:24:06 INFO - mozInstall == 1.12 22:24:06 INFO - mozcrash == 1.0 22:24:06 INFO - mozdebug == 0.1 22:24:06 INFO - mozdevice == 0.48 22:24:06 INFO - mozfile == 1.2 22:24:06 INFO - mozhttpd == 0.7 22:24:06 INFO - mozinfo == 0.9 22:24:06 INFO - mozleak == 0.1 22:24:06 INFO - mozlog == 3.4 22:24:06 INFO - moznetwork == 0.27 22:24:06 INFO - mozprocess == 0.23 22:24:06 INFO - mozprofile == 0.28 22:24:06 INFO - mozrunner == 6.13 22:24:06 INFO - mozscreenshot == 0.1 22:24:06 INFO - mozsystemmonitor == 0.3 22:24:06 INFO - moztest == 0.7 22:24:06 INFO - mozversion == 1.4 22:24:06 INFO - psutil == 3.1.1 22:24:06 INFO - requests == 1.2.3 22:24:06 INFO - wsgiref == 0.1.2 22:24:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:07 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')]} 22:24:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:07 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 0x1055a5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5c95c4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053b9d30>, '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 0x10521fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b1b20>, '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.lyrXMDEQxl/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.Z03uutpdnm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 22:24:07 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 22:24:07 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 22:24:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lyrXMDEQxl/Render', 22:24:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:07 INFO - 'HOME': '/Users/cltbld', 22:24:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:07 INFO - 'LOGNAME': 'cltbld', 22:24:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:07 INFO - 'MOZ_NO_REMOTE': '1', 22:24:07 INFO - 'NO_EM_RESTART': '1', 22:24:07 INFO - 'PAGER': '/bin/cat', 22:24:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:07 INFO - 'PWD': '/builds/slave/test', 22:24:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:07 INFO - 'SHELL': '/bin/bash', 22:24:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z03uutpdnm/Listeners', 22:24:07 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 22:24:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:07 INFO - 'USER': 'cltbld', 22:24:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:07 INFO - 'XPC_FLAGS': '0x0', 22:24:07 INFO - 'XPC_SERVICE_NAME': '0', 22:24:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:07 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)) 22:24:07 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)) 22:24:07 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)) 22:24:07 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)) 22:24:07 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)) 22:24:07 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)) 22:24:07 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)) 22:24:07 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)) 22:24:07 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)) 22:24:07 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)) 22:24:07 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)) 22:24:07 INFO - Cleaning up... 22:24:07 INFO - Return code: 0 22:24:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:24:07 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')]} 22:24:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:07 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')]} 22:24:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:07 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 0x1055a5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5c95c4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053b9d30>, '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 0x10521fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b1b20>, '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.lyrXMDEQxl/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.Z03uutpdnm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 22:24:07 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 22:24:07 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 22:24:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lyrXMDEQxl/Render', 22:24:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:07 INFO - 'HOME': '/Users/cltbld', 22:24:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:07 INFO - 'LOGNAME': 'cltbld', 22:24:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:07 INFO - 'MOZ_NO_REMOTE': '1', 22:24:07 INFO - 'NO_EM_RESTART': '1', 22:24:07 INFO - 'PAGER': '/bin/cat', 22:24:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:07 INFO - 'PWD': '/builds/slave/test', 22:24:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:07 INFO - 'SHELL': '/bin/bash', 22:24:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z03uutpdnm/Listeners', 22:24:07 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 22:24:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:07 INFO - 'USER': 'cltbld', 22:24:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:07 INFO - 'XPC_FLAGS': '0x0', 22:24:07 INFO - 'XPC_SERVICE_NAME': '0', 22:24:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:24:07 INFO - Cleaning up... 22:24:07 INFO - Return code: 0 22:24:07 INFO - Running post-action listener: _resource_record_post_action 22:24:07 INFO - Running post-action listener: _start_resource_monitoring 22:24:07 INFO - Starting resource monitoring. 22:24:07 INFO - [mozharness: 2016-11-30 06:24:07.936613Z] Finished create-virtualenv step (success) 22:24:07 INFO - [mozharness: 2016-11-30 06:24:07.937554Z] Running install step. 22:24:07 INFO - Running pre-action listener: _resource_record_pre_action 22:24:07 INFO - Running main action method: install 22:24:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:24:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:24:08 INFO - Reading from file tmpfile_stdout 22:24:08 INFO - Detecting whether we're running mozinstall >=1.0... 22:24:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:24:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:24:08 INFO - Reading from file tmpfile_stdout 22:24:08 INFO - Output received: 22:24:08 INFO - Usage: mozinstall [options] installer 22:24:08 INFO - Options: 22:24:08 INFO - -h, --help show this help message and exit 22:24:08 INFO - -d DEST, --destination=DEST 22:24:08 INFO - Directory to install application into. [default: 22:24:08 INFO - "/builds/slave/test"] 22:24:08 INFO - --app=APP Application being installed. [default: firefox] 22:24:08 INFO - mkdir: /builds/slave/test/build/application 22:24:08 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'] 22:24:08 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 22:24:39 INFO - Reading from file tmpfile_stdout 22:24:39 INFO - Output received: 22:24:39 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 22:24:39 INFO - Running post-action listener: _resource_record_post_action 22:24:39 INFO - [mozharness: 2016-11-30 06:24:39.387347Z] Finished install step (success) 22:24:39 INFO - [mozharness: 2016-11-30 06:24:39.387485Z] Running run-tests step. 22:24:39 INFO - Running pre-action listener: _resource_record_pre_action 22:24:39 INFO - Running main action method: run_tests 22:24:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:24:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:24:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:24:39 INFO - 2016-11-29 22:24:39.401 screenresolution[1626:7421] starting screenresolution argv=screenresolution get 22:24:39 INFO - 2016-11-29 22:24:39.404 screenresolution[1626:7421] Display 0: 1600x1200x32@60 22:24:39 INFO - 2016-11-29 22:24:39.410 screenresolution[1627:7423] starting screenresolution argv=screenresolution list 22:24:39 INFO - Available Modes on Display 0 22:24:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:24:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:24:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:24:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:24:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:24:39 INFO - Intel Iris: 22:24:39 INFO - Chipset Model: Intel Iris 22:24:39 INFO - Type: GPU 22:24:39 INFO - Bus: Built-In 22:24:39 INFO - VRAM (Dynamic, Max): 1536 MB 22:24:39 INFO - Vendor: Intel (0x8086) 22:24:39 INFO - Device ID: 0x0a2e 22:24:39 INFO - Revision ID: 0x0009 22:24:39 INFO - Displays: 22:24:39 INFO - Display: 22:24:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:24:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:24:39 INFO - Display Serial Number: 1600x1200 60 22:24:39 INFO - Main Display: Yes 22:24:39 INFO - Mirror: Off 22:24:39 INFO - Online: Yes 22:24:39 INFO - Rotation: Supported 22:24:39 INFO - Return code: 0 22:24:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:24:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:24:39 INFO - Python 2.7.3 22:24:39 INFO - Return code: 0 22:24:39 INFO - grabbing minidump binary from tooltool 22:24: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')]} 22:24:39 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053b9d30>, '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 0x10521fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:24:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:24:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:24:39 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:24:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:24:39 INFO - Return code: 0 22:24:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:24:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:24:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:24:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:24:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:24:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0352', '--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 22:24:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0352 --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 22:24:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lyrXMDEQxl/Render', 22:24:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:39 INFO - 'HOME': '/Users/cltbld', 22:24:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:39 INFO - 'LOGNAME': 'cltbld', 22:24:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:24:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:24:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:39 INFO - 'MOZ_NO_REMOTE': '1', 22:24:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:24:39 INFO - 'NO_EM_RESTART': '1', 22:24:39 INFO - 'PAGER': '/bin/cat', 22:24:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:39 INFO - 'PWD': '/builds/slave/test', 22:24:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:24:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:39 INFO - 'SHELL': '/bin/bash', 22:24:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z03uutpdnm/Listeners', 22:24:39 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 22:24:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:39 INFO - 'USER': 'cltbld', 22:24:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:39 INFO - 'XPC_FLAGS': '0x0', 22:24:39 INFO - 'XPC_SERVICE_NAME': '0', 22:24:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0352', '--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 22:24:39 INFO - mozversion application_buildid: 20161129195959 22:24:39 INFO - mozversion application_changeset: 96503957841c8c7617a416719c89a06778de396a 22:24:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:24:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:24:39 INFO - mozversion application_name: Firefox 22:24:39 INFO - mozversion application_remotingname: firefox-dev 22:24:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:24:39 INFO - mozversion application_vendor: Mozilla 22:24:39 INFO - mozversion application_version: 52.0a2 22:24:39 INFO - mozversion platform_buildid: 20161129195959 22:24:39 INFO - mozversion platform_changeset: 96503957841c8c7617a416719c89a06778de396a 22:24:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:24:39 INFO - mozversion platform_version: 52.0a2 22:24:39 INFO - using testdate: 1480487079 22:24:39 INFO - actual date: 1480487079 22:24:39 INFO - starting webserver on 'localhost:49250' 22:24:39 INFO - SUITE-START | Running 2 tests 22:24:39 INFO - TEST-START | dromaeo_css 22:24:39 INFO - operating with platform_type : mac_ 22:24:39 INFO - Initialising browser for dromaeo_css test... 22:24:40 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmp6aLjJL/profile http://localhost:49250/getInfo.html) 22:24:42 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 22:24:42 INFO - PROCESS | 1637 | colorDepth:24 22:24:42 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 22:24:42 INFO - PROCESS | 1637 | __metrics 22:24:42 INFO - PROCESS | 1637 | 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" 22:24:42 INFO - PROCESS | 1637 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:24:43 INFO - TEST-INFO | 1637: exit 0 22:24:43 INFO - Browser initialized. 22:24:43 INFO - Running cycle 1/1 for dromaeo_css test... 22:24:43 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmp6aLjJL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:24:55 INFO - PROCESS | 1639 | RSS: Main: 222531584 22:24:55 INFO - PROCESS | 1639 | 22:25:47 INFO - PROCESS | 1639 | 1480487147407 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] 22:28:00 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49250/tests/dromaeo/cssquery-ext.html) 22:28:02 INFO - PROCESS | 1639 | RSS: Main: 373587968 22:28:02 INFO - PROCESS | 1639 | 22:31:05 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-ext.html (next: http://localhost:49250/tests/dromaeo/cssquery-jquery.html) 22:31:06 INFO - PROCESS | 1639 | RSS: Main: 348364800 22:31:06 INFO - PROCESS | 1639 | 22:34:08 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49250/tests/dromaeo/cssquery-mootools.html) 22:34:09 INFO - PROCESS | 1639 | RSS: Main: 321937408 22:34:09 INFO - PROCESS | 1639 | 22:37:11 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49250/tests/dromaeo/cssquery-prototype.html) 22:37:12 INFO - PROCESS | 1639 | RSS: Main: 328249344 22:37:12 INFO - PROCESS | 1639 | 22:40:15 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49250/tests/dromaeo/cssquery-yui.html) 22:40:16 INFO - PROCESS | 1639 | RSS: Main: 308293632 22:40:16 INFO - PROCESS | 1639 | 22:43:19 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-yui.html (next: http://localhost:49250/tests/dromaeo/cssquery-dojo.html) 22:43:19 INFO - PROCESS | 1639 | RSS: Main: 360050688 22:43:19 INFO - PROCESS | 1639 | 22:43:19 INFO - PROCESS | 1639 | __start_tp_report 22:43:19 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 22:43:19 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 22:43:19 INFO - PROCESS | 1639 | |i|pagename|runs| 22:43:19 INFO - PROCESS | 1639 | |0;dojo.html;2408.072891936604;3349.5143204235387;3367.915802104947;3376.898693039208;3427.0918206675237;5664.660120392779;5666.291713535808;5684.2326285951385;5756.9712151439235;5761.884762304751;5640.435956404358;5689.544836413087;5699.857503562403;5702.859428114373;5763.39484354142;4288.52826189119;5210.4008039075425;5232.16285394337;5259.86850328741;5277.656952298088;5161.329027226455;5200.32395788547;5326.467353264662;5334.386545547253;5334.439883812202;5193.402758682762;5196.090684130262;5244.554212891909;5248.5276325130735;5265.289185959904;5255.237990491377;5288.65623734456;5307.65500242483;5311.415744268127;5326.307580014612;5192.870178245574;5225.790968361261;5258.710770907599;5282.498162674578;5299.5760339172775;5538.75075621598;5557.499825015728;5574.916376254359;5594.804181853657;5677.233573467571;6123.567513702043;6232.781852635181;6250.624962502219;6252.906206406908;6289.0252010938375;6326.145970294044;6332.651704156269;6345.96827015862;6348.587341822767;6357.33248008957;4938.25926111079;5632.662040277596;5672.858178545569;5698.715064246771;5700.9429905701245;5320.441353657888;6308.051655701689;6340.746370145235;6358.523110766619;6371.299157092716;6139.658497107455;6141.263048434127;6142.464383904024;6146.385361463817;6156.261248650101;6114.969425152845;6128.111423843607;6164.4143806338325;6173.074038894202;6262.342454042351;6128.758789208633;6198;6198.0702894565275;6210.937890621036;6221.3156552779155;6182.938170618237;6220.440160385587;6233.0338797486465;6237.906431403532;6243.968780156161;4760.476347601831;4859.854204373874;4896.46138924718;4919.3604831371695;4945.579625731853;4349.630281426114;4396.824127034947;4409;4430.933535996963;4470.949326908174;5674.659520428788;5739.540836733051;5750.482456578928;5757.798477053283;5769.480746732815;6255.718492667841;6274.1843560337775;6289.276733175652;6289.433950944437;6306.211723534559;5591.189277554732;5717.256756621613;5718.971405142947;5761.078227483583;5761.769529218868;5400.891982160335;5407.45925407464;5452.645578037414;5471.042567550663;5479.780808767605;6335.144755458046;6344.365563443619;6366.331535188739;6369.936300636934;6374.298827129012;4765.7140571565815;6158.907616385668;6288.245410550763;6307.432331090224;6370.235571731422;5237.6333656643665;6241.282252541016;6312.43188113072;6319.304876463677;6320.209973753281;6184.165137706352;6192.071189321638;6194.4115309045565;6209.037599172136;6213.068039794067;6108.85814249354;6257.436830685078;6284.057391391145;6289.213848268966;6303.275123360689;5937.495312898452;5955.136505206808;5990.8502287441415;6038.335783063947;6100.786472473442;6080.908786368296;6122.938770612415;6124.6325220486915;6133.018717005258;6153.969230153821;5922.615030023035;5939.91090133657;5943.58394912352;5944.1975333331175;5957.7319020645045;5875.148103525051;6009.308929473166;6022.789202377896;6072.180255665606;6094.847628809138;5945.821625351246;5959.28488581373;5966.224390829338;5967.492763115184;5985.341612422717 22:43:19 INFO - PROCESS | 1639 | |1;ext.html;467.32004932822724;506.3316422322534;523.4242333433224;532.1884126706774;546.6282927609226;30268.697313026863;33002.33995320092;39525.80237098814;40827.79586102073;41206.58793412065;42176.73469795904;42283.78858105705;42358;42414.15171696564;42583.78708106463;9295.07049295072;9482.762930926712;9484.478353690578;9492.905070949271;9507.334486585942;9402.482863442508;9500.71497855062;9513.619455221782;9517.5717092731;9519.904800951972;9473.19477844384;9545.952270238638;9549.236061115094;9554.665586704466;9566.37818541796;9467.479288639122;9502.762430939212;9522.952385238099;9537.093976072261;9537.427754334762;9366.67216647414;9454.385464944757;9474.668386606436;9490.955994840562;9499.097585729414;39655.60344396548;39886;39907.8004609978;39954.80022599868;39997.80001099976;3158.8262645554487;3494.1614012637037;3629.67332940034;3793.412021136728;3797.3544497435228;28153.436931261465;28192.577111343347;28231.435371292664;29815.70184298151;30410.54384184239;3024.5765592817024;3136.2629782001213;3137.137287246003;3168.081256435631;3241.0762932564344;497683;498015;498635;505562;534260.3286983579;57146.714266428404;58843.705781471675;59359;59388.40611593915;59463.108053379234;62759;63092.05361919575;63118;63147.6842615784;63181.684091580166;15900.363985440685;16093;16108.114053727157;16112.838871611368;16125.596860078358;12366.938165309115;12607.684807879876;12620.873791262153;12634.431450584561;12655.936720316524;17025.574360641083;17222.827771722372;18693.439196824118;18723.438296851116;18822.247110115717;7554.43341749371;7772.18392068836;7793.415493837985;7837.451378403457;7890.368770498461;875.9605817738217;925.847235206197;944.7590864329552;1059.89930956559;1093.6172339681048;24736.134235301677;28937.276568085545;29391.55912661269;29513.26216844596;29614.259643508653;783.2715574515641;828.705809437653;852.3479538153317;886.6434355436193;1102.101787043557;14391.568252952427;15007.324670389859;15028.248587570577;15396.615084622974;15401.922990384975;335539;337936;339389;340941;697985;58508;58530.12204816931;58762.70618646879;58863.117053244234;58980.41019589835;1103.8178700514486;1243.3720970909633;1280.9231446113263;1360.5442176870858;1393.470481217131;28480.572791407732;30517.694823051486;30709.38581228318;30845.537316940714;31146.377072458876;48633.756831215615;49042;49051.264231037276;49173.31200160074;49183.75408122937;467042;467933;469814.30185697705;469864;470189;26320.21039368822;26415.867920660272;27210.863945680147;27243.727562724118;27739.722602773712;45395;45678.314825278314;45910.31134532918;45934.54065459436;46253;37333.879983600535;37429.81285093666;38580.807095965465;39572;39907;37110;38292.66350861842;38612.6138738609;39055.804720976215;39551;37060.6293937068;37530.812345938095;38311.42532862065;38398.80800595979;38881.611183888934;31788.523172151974;34368.65631343654;34661.65338346685;34671.30657386889;35796.821015894755;24403;25086.74913250844;25441.982320706964;25610.743892561582;25769.871150644125 22:43:19 INFO - PROCESS | 1639 | |2;jquery.html;300.7368552516548;345.73896707985466;370.42029224264024;375.88911271175;402.2036367991377;14549.636259093528;14781;15750.92124539377;16277.51167464975;19895.104720287585;19248.711269330935;19755.506112347164;20304.390868273975;20413.081411336487;20492.69260961087;5693.3452652944925;6076.635401875879;6082.087686846986;6085.2393450818645;6086.7869624563145;5219.321488206547;5505.284313039299;5937.762489500415;5948.88102237955;6033.42682445167;5871.76512939484;5954.31525374581;5957.582969192158;5979.1031345298115;5994.790182343619;5174.948250517503;5438.619296649236;5980;5981.1028345748055;5995.1906492623375;4973.602111831045;5218.973905130488;5224.9738751306;5234.319538459976;5238.685678859242;18895.62208755817;24224.394390140213;24278;24285.392865178335;24459.63310550343;2228.565429741201;2297.4945511987335;2461.4584791345874;2505.7243703192635;2733.275681944286;10224.539895704711;11503.827442588368;13607.183568985893;14203.289835508287;16049;1789.3200583778212;1806.7380229866726;1846.5937493751487;1906.9332573360005;1916.434651777715;116352.41823790826;116555.66888662179;121109.97225069355;123124.26670908918;127914.99999999907;11349.035331996873;11439.542418303341;11714.941425292818;11715;11948;14223.644408889653;14254.002219844719;14514.927425362806;14609.926950365392;14615.050021748551;8300.335973122257;8539.701110461105;8586.957065214634;8678.522681252593;8684.305255579538;8462.87305690415;8474.3644226682;8474.66101355939;8625.611847466878;8634.956825215833;4440.4227450431235;4543.182227199136;4552.135094332067;4571.657125715585;4593.954060459419;1748.6327871147203;1860.2001139510141;1894.174791069047;1916.3675986924281;1950.7854136045023;604.2839235505852;638.4958135435575;727.3599232675211;838.4675730910916;1043.8486419469134;20862.791372086387;21423.357299281048;21450.03474843636;21573;21616.459588510414;929.4376901974343;931.7856892914667;933.6872147830417;984.9015098490094;1052.847337136111;10774.784504310026;11217.943910280397;11233.494492747846;11275.436228188557;11312.359707842317;544677.2766136223;549572;551197;598214;618177;77126.61250836824;78669.60665196637;114276.85723142662;141163.29418352843;150938;956.7082039977735;963.7301555564453;978.8874279457812;1014.5079636376352;1016.2530540053056;19013.524661883563;19361.903190483958;19753.40739777809;19842.900785495982;19892.60214795667;51356;55161.72419137879;55736.77052918;56583.43416565782;59168;81121;82353.58823205845;84202.15797841943;85342;87842;7795.883061754192;7955.403344749051;8085.595720213848;8213.260806527442;8388.454750441202;11923.10576706733;12556.434960426985;12696.87303126957;13068.281244531641;13241.27173005494;11015.889841101487;11038.227324087558;11129.721756956142;11339.433028348714;11392.08863290918;10946.507407166537;11031.613893513688;11160.218784684994;11234.438278086227;11332.433378331216;9198.448093114435;11079.501422436171;11172.273802202832;11371.43142842871;11403.714907127056;7820.139784623573;7884.763457096296;8269.255766981001;8306.584670766559;8372.539510326991;9741.659041933499;10009.599616015277;10255.7436064099;10388.532516036948;11085.501152447998 22:43:19 INFO - PROCESS | 1639 | |3;mootools.html;1475.4909556203113;1510.380743895003;1676.4383931382986;1805.7742782152231;1847.5104097414187;7897.723579674705;8354.665813367457;8356.582170891446;8359.540225287621;8365.12166222547;7346.449016323771;7591.392688584914;7593.772186834404;7680.539167649945;8156.551389673566;2075.875447473149;2107.062357251024;2222.29997550771;2291.472961218921;2304.781045800646;2110.662294032955;2187.6718492226214;2329.4292898239955;2330.196082351586;2332.148765700524;2244.9102035918545;2293.2776175504746;2297.253392647388;2299.6090664586977;2302.343832007878;2267.104493724986;2272.170657709932;2289.5993201189726;2289.6336586146135;2290;2289.828262880274;2295.644175152854;2319.130326127702;2321.466062805547;2328.161861729776;8199.508029518249;8201.8359632807;8267.917320826835;8283.08886022537;8310.501369917823;5005.57452616528;5151.227315902607;5163.870903227412;5170.095233334151;5170.224466330043;3200.7119359257545;3409.232922592422;3484.163800687842;3487.2851065531504;3510.701590364822;1005.2962925951863;1018.0684673523717;1037.6005237983354;1047.2564479219764;1063.8351055586397;10551;10559.947200264027;10721.571137154504;10723.78552428955;10829.83755243672;4356.215881141362;4508.481524624644;4586.174488592019;4587.17430862448;4640.698354607007;9270.44377337362;9272.258219342437;9283.814323713623;9287.164155226063;9294.163525282622;5149.742512874342;5151.175811870157;5156.535911767959;5157.793688252428;5159.664621799571;3984.382420724792;4458.643308535309;4688.718676879398;4700.482946875841;4701.976490117528;2863.5704644303105;3503.211777350076;3543.2204914918675;3630.8366123523974;3738.4953031340965;1239.7768401687783;1240.1628900492133;1248.095131030014;1253.6489782860813;1258.804885242789;877.4603618774536;882.1355072029447;885.1767855893952;898.2275243290765;910.7085732565515;5446.373667028342;5481.561475081984;5574.108142697149;5594.328680558359;5653.943460565424;968.6658102954469;982.2878413150552;1010.242318261304;1031.1080915008613;1040.9739756506149;3710.3135919854913;3762.360398732222;3780.7353485256317;3850.4994350734787;3895.2209558088493;9636.46999415039;9913.553890074963;10016.248781341428;10022.849657255147;10056;9980.750481238027;9981.45102019396;9981.650642227632;9985.550650220757;9986.850197247048;7561.848763024819;7682.846343073219;7743.612819359009;7853.568053756984;7894.289513943658;5328.014317351302;5354.9464505354445;5477.643953143033;5493.862653433696;5559.193916881949;7836.882446763417;9293.86059209126;9374.578143983674;9379.437233765995;9419.62321507132;8982.416142950688;9354.111359420844;9376.95311523438;9395.953020234854;9424.575894084646;6949.131358580177;7116.822079447848;7121.109861267341;7125.82185445368;7148.463865210234;6938.306169383021;7077.433805295667;7197;7630.8855367170645;7735.613219339123;6622.867542649024;6822.897656535255;7062.823429414306;7094.148702155568;7117.288271172842;6893.4140598047725;6941.167059952838;6946.8610627788175;6953.374196322356;6991.685374158277;6494.512911531559;6628.966855165886;6634.800955971328;6639.3692599203;6639.568428052161;4566.520515345841;4668.323093151542;4668.556487133853;4808.3027960946165;4810.11012962603;4118.773467459198;4733.479317275166;4755.714657120584;4760.59534939534;4945.925811112908 22:43:19 INFO - PROCESS | 1639 | |4;prototype.html;301.1327377153797;303.5128618567201;308.5233314529053;314.7654997027215;315.4668610049017;5186.870328241791;5251.321825868806;5262.315898933143;5311.636321836784;5347.7058761768085;4159.66722662187;4439.356293337472;4707.78814953327;4894.402614078658;4912.435069966955;3554.111472131967;3799.031247032003;4049.1496785675045;4076.0217547788616;4085.673146148301;3361.96638033619;4000.6999475039343;4060.533038700543;4073.816678249471;4088.2232375848644;3995.6603688686496;4019.4171845082446;4021.77880216588;4028.2950483665386;4053.209624123297;3421.4354631486026;3443.2424866529323;3953.8138558432356;4014.8193331300163;4036.8183431745642;2765.5575107982827;2953.5126704094014;3434.8797792077157;3510.666486683761;3544.291141771656;4178.6657067434535;5077.543021128116;5081.720505372203;5131.974340128313;5150.587952963791;3609.2961872434885;4312.762798046107;4498.651202449298;4588.701734387288;4636.887147084675;4836.492168322311;5489;5546.805861794858;5552.167174923753;5571.275734154575;3122.687731226882;3238.9028329150165;3843.5964223756664;3860.5560360558616;3893.143508428141;4593.885152871171;5336.946630533684;5446.319210098738;5698.800541981052;5737.110747834093;3763.5483741950593;3824.655780979725;3903.6291552302487;3962.2075584883137;3971.900702482461;3964.484616999772;3997.140614767839;4002.9199416011515;4003.979980100081;4016.6184212499766;4034;4172.415861779354;4541.75020373876;4583.518730533313;4650.674557751025;4782.569568738831;5536.307961504812;5563.610547261653;5581.49766521015;5645.661260324394;1196.4137572589543;1236.3385588709998;1292.31353084226;1306.817045613615;1312.2848047813964;734.1520543771952;829.788403956987;833.9749807505784;873.3362644390309;898;4080.2859499587453;4516.548345165523;4761.690490118201;4820.998571185977;4847.636427267969;4472.814704103415;4734.881627959328;4917.852464426073;5401.783928642889;5430.321209848769;4279.465066866642;4335.349697545394;4580.0076966519;4841.394825646794;4843.200871856175;2235.6697764830224;2328.9955956815857;2332.2420213430364;2455.7053153621678;2824.5057115004793;5096.190852247473;5474.42518535556;5497.257870187554;5567.85637529546;5573.916391254134;5243.292155559027;5310.4424035476495;5459.426760190123;5955.731992060281;5972.880542389128;4813.638977076734;5722.771089156554;6029.969850150721;6033.849153771191;6038.698065096815;4873.707577545359;5580.525655319343;5783.479486846204;5840.532757379315;5857.9121313179485;4589.406985053434;4961.809165800109;5308.8677680168885;5554.833354999357;5642.23829782974;5188.325517682677;5910.852228694317;5955.166276721349;5980.073088671263;6091.994820854509;3752.549694036733;3849.461075449495;3863.9760463477764;3875.4304506674844;3880.84476620932;5042.899142017212;5681.886362272814;5746.683932383592;5764.250647415809;5769.94230057694;5609.810924894375;5613.915791263216;5620.353659329229;5625.803096891589;5630.184907546227;5280.419153892992;5606.6636001840025;5622.297212848394;5640.869317528811;5651.57613179005;4806.639502037431;5400.810971615974;5481.890362192654;5501.614886958035;5541.2796336476;4392.714473559208;5064.12627327891;5338.866528336823;5435.537979271806;5462.726863656721;5443.237946687388;5448.373437054789;5547.583931205095;5566.192902029264;5577.581681373995 22:43:19 INFO - PROCESS | 1639 | |5;yui.html;685.8936864785958;710.0910834132309;737.9852402951938;763.6678045050403;782.4327362662069;13597.45610175593;14176.574702758933;14195;14207.715845683093;14231.501897433578;12559;12661.177023493467;12767.170133941287;12787;12794.296313702742;631.4569470255576;648.9513286503508;649.3279455763286;650.7592190889378;676.4824908944661;646.227757829394;649.6102338596851;797.7407342613644;822.5845947796355;826.2687521543418;851.5827244650138;861.9525926074064;865.9523726195057;866.618687777376;868.227277722824;858.3433673239977;860.3031544448985;860.561113831944;861.6251930410228;862.310151878501;498.6811327327918;513.6019584821755;515.1371452816518;648;649.3603800256743;11250.49372778227;12814.487420503168;13373;13378;13740.450381984709;13096.47614095435;13359.465621375133;13458.596242112751;13483.258420786853;13513.932430337883;10612;10953.83569246462;11074.723131921686;11101.611443599435;11102.61140860066;3650.7809531427934;3656.597774244831;3657.908552286214;3667.523221981152;3746.4567637692653;83117.16882831215;88273.11726882713;89770.10229897682;91169.08830911672;91259.54370228107;10289.022542858416;10522.368657880552;10527.21045921559;10545.156387488982;10649.094826939641;20673;20777.792222077587;20912.268070617454;20930.895345523175;20971.895140524506;6842.144731908512;7200.855982880313;7275.527090739085;7300.379467745302;7507.21174276704;4071.959280407158;4182.121754431603;4255.3829694694095;4333.523312435629;4390.297552391602;3271.2476130489645;3372.359251742161;3379.341028499419;3611.602723700391;3789.2232092420986;651.8761435327273;682.9726810927507;690.5691964285686;692.62944324786;692.6675195905993;637.7608396851181;655.5083687234574;663.8605892762574;675.1965161457849;691.5055735149392;8623.78440538979;8857.64569417229;8858.512781796933;9021.699466635966;9183.265338772882;597.333972620525;621.8849512840139;650.4763665249425;657.9998402428282;660.379243511098;4353.216421044242;4443.266860967904;4444.3333499974615;4470.307102399133;4573.794179261941;60627.69686151629;76445;85137;91589.85772672089;117424.65150696939;66549.33626659276;68530.65734671394;103791;103945;104665.95334046562;75437.6228118856;92087;92880.96273742185;93856;94704.57943130858;8490.575471226537;8565.743027709179;8615.870761938451;8661.090585488433;8734.344924130588;57818.39424152601;58549;58917;58945.41054589399;60360.396396035474;53735.462645373045;61892.38107619046;62215;82199.5890020546;87312;11813.822792657946;11918.344491053076;12035.45840437165;12214.633560993185;12682.809757853454;12740.745185096432;13299;13834.100783449447;14082.22547759842;14636.121832690073;12237.75524489523;12498.187617804813;13041;13599.048066635241;13814.171149731048;12405.503779849067;12469.750604987668;12534.185277956904;12738.87261127377;13264;11058.447077646246;11392.430378480876;12522.060845436446;12924.353782310658;13192.340382980621;7765.844683106193;10465.372077675363;10526.421046842497;11010.669679909615;11098.667039988813;10127.29108962395;11123.833142502706;11316.434178291218;11382.089432845518;11442.599509017478 22:43:19 INFO - PROCESS | 1639 | __end_tp_report 22:43:19 INFO - PROCESS | 1639 | __start_cc_report 22:43:19 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,1851 22:43:19 INFO - PROCESS | 1639 | __end_cc_report 22:43:19 INFO - PROCESS | 1639 | __startTimestamp1480488199782__endTimestamp 22:43:19 INFO - PROCESS | 1639 | 22:43:19 INFO - PROCESS | 1639 | ------- Summary: start ------- 22:43:19 INFO - PROCESS | 1639 | Number of tests: 6 22:43:19 INFO - PROCESS | 1639 | 22:43:19 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5716.19 Median:5943.58 stddev:675.61 (11.4%) stddev-sans-first:625.86 22:43:19 INFO - PROCESS | 1639 | Values: 2408.1 3349.5 3367.9 3376.9 3427.1 5664.7 5666.3 5684.2 5757.0 5761.9 5640.4 5689.5 5699.9 5702.9 5763.4 4288.5 5210.4 5232.2 5259.9 5277.7 5161.3 5200.3 5326.5 5334.4 5334.4 5193.4 5196.1 5244.6 5248.5 5265.3 5255.2 5288.7 5307.7 5311.4 5326.3 5192.9 5225.8 5258.7 5282.5 5299.6 5538.8 5557.5 5574.9 5594.8 5677.2 6123.6 6232.8 6250.6 6252.9 6289.0 6326.1 6332.7 6346.0 6348.6 6357.3 4938.3 5632.7 5672.9 5698.7 5700.9 5320.4 6308.1 6340.7 6358.5 6371.3 6139.7 6141.3 6142.5 6146.4 6156.3 6115.0 6128.1 6164.4 6173.1 6262.3 6128.8 6198.0 6198.1 6210.9 6221.3 6182.9 6220.4 6233.0 6237.9 6244.0 4760.5 4859.9 4896.5 4919.4 4945.6 4349.6 4396.8 4409.0 4430.9 4470.9 5674.7 5739.5 5750.5 5757.8 5769.5 6255.7 6274.2 6289.3 6289.4 6306.2 5591.2 5717.3 5719.0 5761.1 5761.8 5400.9 5407.5 5452.6 5471.0 5479.8 6335.1 6344.4 6366.3 6369.9 6374.3 4765.7 6158.9 6288.2 6307.4 6370.2 5237.6 6241.3 6312.4 6319.3 6320.2 6184.2 6192.1 6194.4 6209.0 6213.1 6108.9 6257.4 6284.1 6289.2 6303.3 5937.5 5955.1 5990.9 6038.3 6100.8 6080.9 6122.9 6124.6 6133.0 6154.0 5922.6 5939.9 5943.6 5944.2 5957.7 5875.1 6009.3 6022.8 6072.2 6094.8 5945.8 5959.3 5966.2 5967.5 5985.3 22:43:19 INFO - PROCESS | 1639 | 22:43:19 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:61044.61 Median:39896.50 stddev:125420.22 (314.4%) stddev-sans-first:125689.59 22:43:19 INFO - PROCESS | 1639 | Values: 467.3 506.3 523.4 532.2 546.6 30268.7 33002.3 39525.8 40827.8 41206.6 42176.7 42283.8 42358.0 42414.2 42583.8 9295.1 9482.8 9484.5 9492.9 9507.3 9402.5 9500.7 9513.6 9517.6 9519.9 9473.2 9546.0 9549.2 9554.7 9566.4 9467.5 9502.8 9523.0 9537.1 9537.4 9366.7 9454.4 9474.7 9491.0 9499.1 39655.6 39886.0 39907.8 39954.8 39997.8 3158.8 3494.2 3629.7 3793.4 3797.4 28153.4 28192.6 28231.4 29815.7 30410.5 3024.6 3136.3 3137.1 3168.1 3241.1 497683.0 498015.0 498635.0 505562.0 534260.3 57146.7 58843.7 59359.0 59388.4 59463.1 62759.0 63092.1 63118.0 63147.7 63181.7 15900.4 16093.0 16108.1 16112.8 16125.6 12366.9 12607.7 12620.9 12634.4 12655.9 17025.6 17222.8 18693.4 18723.4 18822.2 7554.4 7772.2 7793.4 7837.5 7890.4 876.0 925.8 944.8 1059.9 1093.6 24736.1 28937.3 29391.6 29513.3 29614.3 783.3 828.7 852.3 886.6 1102.1 14391.6 15007.3 15028.2 15396.6 15401.9 335539.0 337936.0 339389.0 340941.0 697985.0 58508.0 58530.1 58762.7 58863.1 58980.4 1103.8 1243.4 1280.9 1360.5 1393.5 28480.6 30517.7 30709.4 30845.5 31146.4 48633.8 49042.0 49051.3 49173.3 49183.8 467042.0 467933.0 469814.3 469864.0 470189.0 26320.2 26415.9 27210.9 27243.7 27739.7 45395.0 45678.3 45910.3 45934.5 46253.0 37333.9 37429.8 38580.8 39572.0 39907.0 37110.0 38292.7 38612.6 39055.8 39551.0 37060.6 37530.8 38311.4 38398.8 38881.6 31788.5 34368.7 34661.7 34671.3 35796.8 24403.0 25086.7 25442.0 25610.7 25769.9 22:43:19 INFO - PROCESS | 1639 | 22:43:19 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:34118.62 Median:24372.51 stddev:95841.26 (393.2%) stddev-sans-first:96076.48 22:43:19 INFO - PROCESS | 1639 | Values: 300.7 345.7 370.4 375.9 402.2 14549.6 14781.0 15750.9 16277.5 19895.1 19248.7 19755.5 20304.4 20413.1 20492.7 5693.3 6076.6 6082.1 6085.2 6086.8 5219.3 5505.3 5937.8 5948.9 6033.4 5871.8 5954.3 5957.6 5979.1 5994.8 5174.9 5438.6 5980.0 5981.1 5995.2 4973.6 5219.0 5225.0 5234.3 5238.7 18895.6 24224.4 24278.0 24285.4 24459.6 2228.6 2297.5 2461.5 2505.7 2733.3 10224.5 11503.8 13607.2 14203.3 16049.0 1789.3 1806.7 1846.6 1906.9 1916.4 116352.4 116555.7 121110.0 123124.3 127915.0 11349.0 11439.5 11714.9 11715.0 11948.0 14223.6 14254.0 14514.9 14609.9 14615.1 8300.3 8539.7 8587.0 8678.5 8684.3 8462.9 8474.4 8474.7 8625.6 8635.0 4440.4 4543.2 4552.1 4571.7 4594.0 1748.6 1860.2 1894.2 1916.4 1950.8 604.3 638.5 727.4 838.5 1043.8 20862.8 21423.4 21450.0 21573.0 21616.5 929.4 931.8 933.7 984.9 1052.8 10774.8 11217.9 11233.5 11275.4 11312.4 544677.3 549572.0 551197.0 598214.0 618177.0 77126.6 78669.6 114276.9 141163.3 150938.0 956.7 963.7 978.9 1014.5 1016.3 19013.5 19361.9 19753.4 19842.9 19892.6 51356.0 55161.7 55736.8 56583.4 59168.0 81121.0 82353.6 84202.2 85342.0 87842.0 7795.9 7955.4 8085.6 8213.3 8388.5 11923.1 12556.4 12696.9 13068.3 13241.3 11015.9 11038.2 11129.7 11339.4 11392.1 10946.5 11031.6 11160.2 11234.4 11332.4 9198.4 11079.5 11172.3 11371.4 11403.7 7820.1 7884.8 8269.3 8306.6 8372.5 9741.7 10009.6 10255.7 10388.5 11085.5 22:43:19 INFO - PROCESS | 1639 | 22:43:19 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5350.81 Median:5157.16 stddev:2922.73 (56.7%) stddev-sans-first:2916.42 22:43:19 INFO - PROCESS | 1639 | Values: 1475.5 1510.4 1676.4 1805.8 1847.5 7897.7 8354.7 8356.6 8359.5 8365.1 7346.4 7591.4 7593.8 7680.5 8156.6 2075.9 2107.1 2222.3 2291.5 2304.8 2110.7 2187.7 2329.4 2330.2 2332.1 2244.9 2293.3 2297.3 2299.6 2302.3 2267.1 2272.2 2289.6 2289.6 2290.0 2289.8 2295.6 2319.1 2321.5 2328.2 8199.5 8201.8 8267.9 8283.1 8310.5 5005.6 5151.2 5163.9 5170.1 5170.2 3200.7 3409.2 3484.2 3487.3 3510.7 1005.3 1018.1 1037.6 1047.3 1063.8 10551.0 10559.9 10721.6 10723.8 10829.8 4356.2 4508.5 4586.2 4587.2 4640.7 9270.4 9272.3 9283.8 9287.2 9294.2 5149.7 5151.2 5156.5 5157.8 5159.7 3984.4 4458.6 4688.7 4700.5 4702.0 2863.6 3503.2 3543.2 3630.8 3738.5 1239.8 1240.2 1248.1 1253.6 1258.8 877.5 882.1 885.2 898.2 910.7 5446.4 5481.6 5574.1 5594.3 5653.9 968.7 982.3 1010.2 1031.1 1041.0 3710.3 3762.4 3780.7 3850.5 3895.2 9636.5 9913.6 10016.2 10022.8 10056.0 9980.8 9981.5 9981.7 9985.6 9986.9 7561.8 7682.8 7743.6 7853.6 7894.3 5328.0 5354.9 5477.6 5493.9 5559.2 7836.9 9293.9 9374.6 9379.4 9419.6 8982.4 9354.1 9377.0 9396.0 9424.6 6949.1 7116.8 7121.1 7125.8 7148.5 6938.3 7077.4 7197.0 7630.9 7735.6 6622.9 6822.9 7062.8 7094.1 7117.3 6893.4 6941.2 6946.9 6953.4 6991.7 6494.5 6629.0 6634.8 6639.4 6639.6 4566.5 4668.3 4668.6 4808.3 4810.1 4118.8 4733.5 4755.7 4760.6 4945.9 22:43:19 INFO - PROCESS | 1639 | 22:43:19 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4419.47 Median:4842.30 stddev:1391.01 (28.7%) stddev-sans-first:1360.13 22:43:19 INFO - PROCESS | 1639 | Values: 301.1 303.5 308.5 314.8 315.5 5186.9 5251.3 5262.3 5311.6 5347.7 4159.7 4439.4 4707.8 4894.4 4912.4 3554.1 3799.0 4049.1 4076.0 4085.7 3362.0 4000.7 4060.5 4073.8 4088.2 3995.7 4019.4 4021.8 4028.3 4053.2 3421.4 3443.2 3953.8 4014.8 4036.8 2765.6 2953.5 3434.9 3510.7 3544.3 4178.7 5077.5 5081.7 5132.0 5150.6 3609.3 4312.8 4498.7 4588.7 4636.9 4836.5 5489.0 5546.8 5552.2 5571.3 3122.7 3238.9 3843.6 3860.6 3893.1 4593.9 5336.9 5446.3 5698.8 5737.1 3763.5 3824.7 3903.6 3962.2 3971.9 3964.5 3997.1 4002.9 4004.0 4016.6 4034.0 4172.4 4541.8 4583.5 4650.7 4782.6 5536.3 5563.6 5581.5 5645.7 1196.4 1236.3 1292.3 1306.8 1312.3 734.2 829.8 834.0 873.3 898.0 4080.3 4516.5 4761.7 4821.0 4847.6 4472.8 4734.9 4917.9 5401.8 5430.3 4279.5 4335.3 4580.0 4841.4 4843.2 2235.7 2329.0 2332.2 2455.7 2824.5 5096.2 5474.4 5497.3 5567.9 5573.9 5243.3 5310.4 5459.4 5955.7 5972.9 4813.6 5722.8 6030.0 6033.8 6038.7 4873.7 5580.5 5783.5 5840.5 5857.9 4589.4 4961.8 5308.9 5554.8 5642.2 5188.3 5910.9 5955.2 5980.1 6092.0 3752.5 3849.5 3864.0 3875.4 3880.8 5042.9 5681.9 5746.7 5764.3 5769.9 5609.8 5613.9 5620.4 5625.8 5630.2 5280.4 5606.7 5622.3 5640.9 5651.6 4806.6 5400.8 5481.9 5501.6 5541.3 4392.7 5064.1 5338.9 5435.5 5462.7 5443.2 5448.4 5547.6 5566.2 5577.6 22:43:19 INFO - PROCESS | 1639 | 22:43:19 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:19725.60 Median:4457.32 stddev:28591.36 (641.4%) stddev-sans-first:28635.83 22:43:19 INFO - PROCESS | 1639 | Values: 685.9 710.1 738.0 763.7 782.4 13597.5 14176.6 14195.0 14207.7 14231.5 12559.0 12661.2 12767.2 12787.0 12794.3 631.5 649.0 649.3 650.8 676.5 646.2 649.6 797.7 822.6 826.3 851.6 862.0 866.0 866.6 868.2 858.3 860.3 860.6 861.6 862.3 498.7 513.6 515.1 648.0 649.4 11250.5 12814.5 13373.0 13378.0 13740.5 13096.5 13359.5 13458.6 13483.3 13513.9 10612.0 10953.8 11074.7 11101.6 11102.6 3650.8 3656.6 3657.9 3667.5 3746.5 83117.2 88273.1 89770.1 91169.1 91259.5 10289.0 10522.4 10527.2 10545.2 10649.1 20673.0 20777.8 20912.3 20930.9 20971.9 6842.1 7200.9 7275.5 7300.4 7507.2 4072.0 4182.1 4255.4 4333.5 4390.3 3271.2 3372.4 3379.3 3611.6 3789.2 651.9 683.0 690.6 692.6 692.7 637.8 655.5 663.9 675.2 691.5 8623.8 8857.6 8858.5 9021.7 9183.3 597.3 621.9 650.5 658.0 660.4 4353.2 4443.3 4444.3 4470.3 4573.8 60627.7 76445.0 85137.0 91589.9 117424.7 66549.3 68530.7 103791.0 103945.0 104666.0 75437.6 92087.0 92881.0 93856.0 94704.6 8490.6 8565.7 8615.9 8661.1 8734.3 57818.4 58549.0 58917.0 58945.4 60360.4 53735.5 61892.4 62215.0 82199.6 87312.0 11813.8 11918.3 12035.5 12214.6 12682.8 12740.7 13299.0 13834.1 14082.2 14636.1 12237.8 12498.2 13041.0 13599.0 13814.2 12405.5 12469.8 12534.2 12738.9 13264.0 11058.4 11392.4 12522.1 12924.4 13192.3 7765.8 10465.4 10526.4 11010.7 11098.7 10127.3 11123.8 11316.4 11382.1 11442.6 22:43:19 INFO - PROCESS | 1639 | -------- Summary: end -------- 22:43:19 INFO - PROCESS | 1639 | 22:43:20 INFO - TEST-INFO | 1639: exit 0 22:43:20 INFO - TEST-OK | dromaeo_css | took 1120460ms 22:43:20 INFO - TEST-START | kraken 22:43:20 INFO - operating with platform_type : mac_ 22:43:20 INFO - Initialising browser for kraken test... 22:43:20 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmpd4HdvF/profile http://localhost:49250/getInfo.html) 22:43:23 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 22:43:23 INFO - PROCESS | 1650 | colorDepth:24 22:43:23 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 22:43:23 INFO - PROCESS | 1650 | __metrics 22:43:23 INFO - PROCESS | 1650 | 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" 22:43:23 INFO - PROCESS | 1650 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:43:24 INFO - TEST-INFO | 1650: exit 0 22:43:24 INFO - Browser initialized. 22:43:24 INFO - Running cycle 1/1 for kraken test... 22:43:24 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmpd4HdvF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:43:36 INFO - PROCESS | 1651 | RSS: Main: 222752768 22:43:36 INFO - PROCESS | 1651 | 22:43:39 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection) 22:43:40 INFO - PROCESS | 1651 | RSS: Main: 273453056 22:43:40 INFO - PROCESS | 1651 | 22:43:42 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-dft) 22:43:43 INFO - PROCESS | 1651 | RSS: Main: 284569600 22:43:43 INFO - PROCESS | 1651 | 22:43:46 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-fft) 22:43:47 INFO - PROCESS | 1651 | RSS: Main: 379424768 22:43:47 INFO - PROCESS | 1651 | 22:43:49 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator) 22:43:49 INFO - PROCESS | 1651 | RSS: Main: 283156480 22:43:49 INFO - PROCESS | 1651 | 22:43:51 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:43:52 INFO - PROCESS | 1651 | RSS: Main: 284299264 22:43:52 INFO - PROCESS | 1651 | 22:43:55 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom) 22:43:56 INFO - PROCESS | 1651 | RSS: Main: 325677056 22:43:56 INFO - PROCESS | 1651 | 22:43:59 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate) 22:43:59 INFO - PROCESS | 1651 | RSS: Main: 324755456 22:43:59 INFO - PROCESS | 1651 | 22:44:02 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial) 22:44:03 INFO - PROCESS | 1651 | RSS: Main: 322932736 22:44:03 INFO - PROCESS | 1651 | 22:44:04 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:44:05 INFO - PROCESS | 1651 | RSS: Main: 298831872 22:44:05 INFO - PROCESS | 1651 | 22:44:07 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:44:07 INFO - PROCESS | 1651 | RSS: Main: 297811968 22:44:07 INFO - PROCESS | 1651 | 22:44:10 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:44:10 INFO - PROCESS | 1651 | RSS: Main: 301301760 22:44:10 INFO - PROCESS | 1651 | 22:44:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:44:14 INFO - PROCESS | 1651 | RSS: Main: 323129344 22:44:14 INFO - PROCESS | 1651 | 22:44:17 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:44:18 INFO - PROCESS | 1651 | RSS: Main: 315949056 22:44:18 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49250/tests/kraken/driver.html?testName=ai-astar) 22:44:20 INFO - PROCESS | 1651 | RSS: Main: 359911424 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | __start_tp_report 22:44:20 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 22:44:20 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 22:44:20 INFO - PROCESS | 1651 | |i|pagename|runs| 22:44:20 INFO - PROCESS | 1651 | |0;ai-astar;116;116;119;164;170;114;115;115;116;114 22:44:20 INFO - PROCESS | 1651 | |1;audio-beat-detection;151;115;113;149;116;118;100;122;178;143 22:44:20 INFO - PROCESS | 1651 | |2;audio-dft;185;184;184;184;182;183;220;264;192;183 22:44:20 INFO - PROCESS | 1651 | |3;audio-fft;83;72;60;89;62;59;60;59;82;67 22:44:20 INFO - PROCESS | 1651 | |4;audio-oscillator;87;70;97;70;68;70;90;68;69;92 22:44:20 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;89;88;135;131;89;85;90;87;90;88 22:44:20 INFO - PROCESS | 1651 | |6;imaging-darkroom;102;98;106;163;168;97;101;97;97;98 22:44:20 INFO - PROCESS | 1651 | |7;imaging-desaturate;81;82;79;79;82;80;84;85;81;83 22:44:20 INFO - PROCESS | 1651 | |8;json-parse-financial;55;55;53;55;57;58;58;57;58;58 22:44:20 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;66;74;67;76;75;68;69;85;102;104 22:44:20 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;86;86;88;86;85;83;108;101;104;82 22:44:20 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;148;146;146;147;150;142;174;162;227;164 22:44:20 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;230;228;173;169;171;168;166;171;166;181 22:44:20 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;66;68;66;67;67;68;66;64;66;66 22:44:20 INFO - PROCESS | 1651 | __end_tp_report 22:44:20 INFO - PROCESS | 1651 | __start_cc_report 22:44:20 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2028 22:44:20 INFO - PROCESS | 1651 | __end_cc_report 22:44:20 INFO - PROCESS | 1651 | __startTimestamp1480488260281__endTimestamp 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | ------- Summary: start ------- 22:44:20 INFO - PROCESS | 1651 | Number of tests: 14 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:125.90 Median:116.00 stddev:21.75 (18.8%) stddev-sans-first:22.78 22:44:20 INFO - PROCESS | 1651 | Values: 116.0 116.0 119.0 164.0 170.0 114.0 115.0 115.0 116.0 114.0 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:130.50 Median:132.50 stddev:23.78 (17.9%) stddev-sans-first:24.04 22:44:20 INFO - PROCESS | 1651 | Values: 151.0 115.0 113.0 149.0 116.0 118.0 100.0 122.0 178.0 143.0 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:196.10 Median:184.50 stddev:26.46 (14.3%) stddev-sans-first:27.76 22:44:20 INFO - PROCESS | 1651 | Values: 185.0 184.0 184.0 184.0 182.0 183.0 220.0 264.0 192.0 183.0 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:69.30 Median:69.50 stddev:11.49 (16.5%) stddev-sans-first:11.07 22:44:20 INFO - PROCESS | 1651 | Values: 83.0 72.0 60.0 89.0 62.0 59.0 60.0 59.0 82.0 67.0 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:78.10 Median:78.50 stddev:11.81 (15.0%) stddev-sans-first:12.08 22:44:20 INFO - PROCESS | 1651 | Values: 87.0 70.0 97.0 70.0 68.0 70.0 90.0 68.0 69.0 92.0 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:97.20 Median:88.50 stddev:18.95 (21.4%) stddev-sans-first:19.86 22:44:20 INFO - PROCESS | 1651 | Values: 89.0 88.0 135.0 131.0 89.0 85.0 90.0 87.0 90.0 88.0 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:112.70 Median:97.00 stddev:28.00 (28.9%) stddev-sans-first:29.43 22:44:20 INFO - PROCESS | 1651 | Values: 102.0 98.0 106.0 163.0 168.0 97.0 101.0 97.0 97.0 98.0 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:81.60 Median:82.00 stddev:2.01 (2.5%) stddev-sans-first:2.12 22:44:20 INFO - PROCESS | 1651 | Values: 81.0 82.0 79.0 79.0 82.0 80.0 84.0 85.0 81.0 83.0 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:57.50 stddev:1.78 (3.1%) stddev-sans-first:1.81 22:44:20 INFO - PROCESS | 1651 | Values: 55.0 55.0 53.0 55.0 57.0 58.0 58.0 57.0 58.0 58.0 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:78.60 Median:71.50 stddev:14.03 (19.6%) stddev-sans-first:14.12 22:44:20 INFO - PROCESS | 1651 | Values: 66.0 74.0 67.0 76.0 75.0 68.0 69.0 85.0 102.0 104.0 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:85.50 stddev:9.56 (11.2%) stddev-sans-first:9.98 22:44:20 INFO - PROCESS | 1651 | Values: 86.0 86.0 88.0 86.0 85.0 83.0 108.0 101.0 104.0 82.0 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:160.60 Median:156.00 stddev:25.45 (16.3%) stddev-sans-first:26.58 22:44:20 INFO - PROCESS | 1651 | Values: 148.0 146.0 146.0 147.0 150.0 142.0 174.0 162.0 227.0 164.0 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.30 Median:172.00 stddev:24.99 (14.5%) stddev-sans-first:19.66 22:44:20 INFO - PROCESS | 1651 | Values: 230.0 228.0 173.0 169.0 171.0 168.0 166.0 171.0 166.0 181.0 22:44:20 INFO - PROCESS | 1651 | 22:44:20 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.50 stddev:1.17 (1.8%) stddev-sans-first:1.24 22:44:20 INFO - PROCESS | 1651 | Values: 66.0 68.0 66.0 67.0 67.0 68.0 66.0 64.0 66.0 66.0 22:44:20 INFO - PROCESS | 1651 | -------- Summary: end -------- 22:44:20 INFO - PROCESS | 1651 | 22:44:21 INFO - TEST-INFO | 1651: exit 0 22:44:21 INFO - TEST-OK | kraken | took 60886ms 22:44:21 INFO - SUITE-END | took 1181s 22:44:21 INFO - Completed test suite (00:19:41) 22:44:21 INFO - javascript benchmark 22:44:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2408.072891936604, 3349.5143204235387, 3367.915802104947, 3376.898693039208, 3427.0918206675237, 5664.660120392779, 5666.291713535808, 5684.2326285951385, 5756.9712151439235, 5761.884762304751, 5640.435956404358, 5689.544836413087, 5699.857503562403, 5702.859428114373, 5763.39484354142, 4288.52826189119, 5210.4008039075425, 5232.16285394337, 5259.86850328741, 5277.656952298088, 5161.329027226455, 5200.32395788547, 5326.467353264662, 5334.386545547253, 5334.439883812202, 5193.402758682762, 5196.090684130262, 5244.554212891909, 5248.5276325130735, 5265.289185959904, 5255.237990491377, 5288.65623734456, 5307.65500242483, 5311.415744268127, 5326.307580014612, 5192.870178245574, 5225.790968361261, 5258.710770907599, 5282.498162674578, 5299.5760339172775, 5538.75075621598, 5557.499825015728, 5574.916376254359, 5594.804181853657, 5677.233573467571, 6123.567513702043, 6232.781852635181, 6250.624962502219, 6252.906206406908, 6289.0252010938375, 6326.145970294044, 6332.651704156269, 6345.96827015862, 6348.587341822767, 6357.33248008957, 4938.25926111079, 5632.662040277596, 5672.858178545569, 5698.715064246771, 5700.9429905701245, 5320.441353657888, 6308.051655701689, 6340.746370145235, 6358.523110766619, 6371.299157092716, 6139.658497107455, 6141.263048434127, 6142.464383904024, 6146.385361463817, 6156.261248650101, 6114.969425152845, 6128.111423843607, 6164.4143806338325, 6173.074038894202, 6262.342454042351, 6128.758789208633, 6198.0, 6198.0702894565275, 6210.937890621036, 6221.3156552779155, 6182.938170618237, 6220.440160385587, 6233.0338797486465, 6237.906431403532, 6243.968780156161, 4760.476347601831, 4859.854204373874, 4896.46138924718, 4919.3604831371695, 4945.579625731853, 4349.630281426114, 4396.824127034947, 4409.0, 4430.933535996963, 4470.949326908174, 5674.659520428788, 5739.540836733051, 5750.482456578928, 5757.798477053283, 5769.480746732815, 6255.718492667841, 6274.1843560337775, 6289.276733175652, 6289.433950944437, 6306.211723534559, 5591.189277554732, 5717.256756621613, 5718.971405142947, 5761.078227483583, 5761.769529218868, 5400.891982160335, 5407.45925407464, 5452.645578037414, 5471.042567550663, 5479.780808767605, 6335.144755458046, 6344.365563443619, 6366.331535188739, 6369.936300636934, 6374.298827129012, 4765.7140571565815, 6158.907616385668, 6288.245410550763, 6307.432331090224, 6370.235571731422, 5237.6333656643665, 6241.282252541016, 6312.43188113072, 6319.304876463677, 6320.209973753281, 6184.165137706352, 6192.071189321638, 6194.4115309045565, 6209.037599172136, 6213.068039794067, 6108.85814249354, 6257.436830685078, 6284.057391391145, 6289.213848268966, 6303.275123360689, 5937.495312898452, 5955.136505206808, 5990.8502287441415, 6038.335783063947, 6100.786472473442, 6080.908786368296, 6122.938770612415, 6124.6325220486915, 6133.018717005258, 6153.969230153821, 5922.615030023035, 5939.91090133657, 5943.58394912352, 5944.1975333331175, 5957.7319020645045, 5875.148103525051, 6009.308929473166, 6022.789202377896, 6072.180255665606, 6094.847628809138, 5945.821625351246, 5959.28488581373, 5966.224390829338, 5967.492763115184, 5985.341612422717], "value": 5671.396592245656, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.32004932822724, 506.3316422322534, 523.4242333433224, 532.1884126706774, 546.6282927609226, 30268.697313026863, 33002.33995320092, 39525.80237098814, 40827.79586102073, 41206.58793412065, 42176.73469795904, 42283.78858105705, 42358.0, 42414.15171696564, 42583.78708106463, 9295.07049295072, 9482.762930926712, 9484.478353690578, 9492.905070949271, 9507.334486585942, 9402.482863442508, 9500.71497855062, 9513.619455221782, 9517.5717092731, 9519.904800951972, 9473.19477844384, 9545.952270238638, 9549.236061115094, 9554.665586704466, 9566.37818541796, 9467.479288639122, 9502.762430939212, 9522.952385238099, 9537.093976072261, 9537.427754334762, 9366.67216647414, 9454.385464944757, 9474.668386606436, 9490.955994840562, 9499.097585729414, 39655.60344396548, 39886.0, 39907.8004609978, 39954.80022599868, 39997.80001099976, 3158.8262645554487, 3494.1614012637037, 3629.67332940034, 3793.412021136728, 3797.3544497435228, 28153.436931261465, 28192.577111343347, 28231.435371292664, 29815.70184298151, 30410.54384184239, 3024.5765592817024, 3136.2629782001213, 3137.137287246003, 3168.081256435631, 3241.0762932564344, 497683.0, 498015.0, 498635.0, 505562.0, 534260.3286983579, 57146.714266428404, 58843.705781471675, 59359.0, 59388.40611593915, 59463.108053379234, 62759.0, 63092.05361919575, 63118.0, 63147.6842615784, 63181.684091580166, 15900.363985440685, 16093.0, 16108.114053727157, 16112.838871611368, 16125.596860078358, 12366.938165309115, 12607.684807879876, 12620.873791262153, 12634.431450584561, 12655.936720316524, 17025.574360641083, 17222.827771722372, 18693.439196824118, 18723.438296851116, 18822.247110115717, 7554.43341749371, 7772.18392068836, 7793.415493837985, 7837.451378403457, 7890.368770498461, 875.9605817738217, 925.847235206197, 944.7590864329552, 1059.89930956559, 1093.6172339681048, 24736.134235301677, 28937.276568085545, 29391.55912661269, 29513.26216844596, 29614.259643508653, 783.2715574515641, 828.705809437653, 852.3479538153317, 886.6434355436193, 1102.101787043557, 14391.568252952427, 15007.324670389859, 15028.248587570577, 15396.615084622974, 15401.922990384975, 335539.0, 337936.0, 339389.0, 340941.0, 697985.0, 58508.0, 58530.12204816931, 58762.70618646879, 58863.117053244234, 58980.41019589835, 1103.8178700514486, 1243.3720970909633, 1280.9231446113263, 1360.5442176870858, 1393.470481217131, 28480.572791407732, 30517.694823051486, 30709.38581228318, 30845.537316940714, 31146.377072458876, 48633.756831215615, 49042.0, 49051.264231037276, 49173.31200160074, 49183.75408122937, 467042.0, 467933.0, 469814.30185697705, 469864.0, 470189.0, 26320.21039368822, 26415.867920660272, 27210.863945680147, 27243.727562724118, 27739.722602773712, 45395.0, 45678.314825278314, 45910.31134532918, 45934.54065459436, 46253.0, 37333.879983600535, 37429.81285093666, 38580.807095965465, 39572.0, 39907.0, 37110.0, 38292.66350861842, 38612.6138738609, 39055.804720976215, 39551.0, 37060.6293937068, 37530.812345938095, 38311.42532862065, 38398.80800595979, 38881.611183888934, 31788.523172151974, 34368.65631343654, 34661.65338346685, 34671.30657386889, 35796.821015894755, 24403.0, 25086.74913250844, 25441.982320706964, 25610.743892561582, 25769.871150644125], "value": 19351.26181384945, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.7368552516548, 345.73896707985466, 370.42029224264024, 375.88911271175, 402.2036367991377, 14549.636259093528, 14781.0, 15750.92124539377, 16277.51167464975, 19895.104720287585, 19248.711269330935, 19755.506112347164, 20304.390868273975, 20413.081411336487, 20492.69260961087, 5693.3452652944925, 6076.635401875879, 6082.087686846986, 6085.2393450818645, 6086.7869624563145, 5219.321488206547, 5505.284313039299, 5937.762489500415, 5948.88102237955, 6033.42682445167, 5871.76512939484, 5954.31525374581, 5957.582969192158, 5979.1031345298115, 5994.790182343619, 5174.948250517503, 5438.619296649236, 5980.0, 5981.1028345748055, 5995.1906492623375, 4973.602111831045, 5218.973905130488, 5224.9738751306, 5234.319538459976, 5238.685678859242, 18895.62208755817, 24224.394390140213, 24278.0, 24285.392865178335, 24459.63310550343, 2228.565429741201, 2297.4945511987335, 2461.4584791345874, 2505.7243703192635, 2733.275681944286, 10224.539895704711, 11503.827442588368, 13607.183568985893, 14203.289835508287, 16049.0, 1789.3200583778212, 1806.7380229866726, 1846.5937493751487, 1906.9332573360005, 1916.434651777715, 116352.41823790826, 116555.66888662179, 121109.97225069355, 123124.26670908918, 127914.99999999907, 11349.035331996873, 11439.542418303341, 11714.941425292818, 11715.0, 11948.0, 14223.644408889653, 14254.002219844719, 14514.927425362806, 14609.926950365392, 14615.050021748551, 8300.335973122257, 8539.701110461105, 8586.957065214634, 8678.522681252593, 8684.305255579538, 8462.87305690415, 8474.3644226682, 8474.66101355939, 8625.611847466878, 8634.956825215833, 4440.4227450431235, 4543.182227199136, 4552.135094332067, 4571.657125715585, 4593.954060459419, 1748.6327871147203, 1860.2001139510141, 1894.174791069047, 1916.3675986924281, 1950.7854136045023, 604.2839235505852, 638.4958135435575, 727.3599232675211, 838.4675730910916, 1043.8486419469134, 20862.791372086387, 21423.357299281048, 21450.03474843636, 21573.0, 21616.459588510414, 929.4376901974343, 931.7856892914667, 933.6872147830417, 984.9015098490094, 1052.847337136111, 10774.784504310026, 11217.943910280397, 11233.494492747846, 11275.436228188557, 11312.359707842317, 544677.2766136223, 549572.0, 551197.0, 598214.0, 618177.0, 77126.61250836824, 78669.60665196637, 114276.85723142662, 141163.29418352843, 150938.0, 956.7082039977735, 963.7301555564453, 978.8874279457812, 1014.5079636376352, 1016.2530540053056, 19013.524661883563, 19361.903190483958, 19753.40739777809, 19842.900785495982, 19892.60214795667, 51356.0, 55161.72419137879, 55736.77052918, 56583.43416565782, 59168.0, 81121.0, 82353.58823205845, 84202.15797841943, 85342.0, 87842.0, 7795.883061754192, 7955.403344749051, 8085.595720213848, 8213.260806527442, 8388.454750441202, 11923.10576706733, 12556.434960426985, 12696.87303126957, 13068.281244531641, 13241.27173005494, 11015.889841101487, 11038.227324087558, 11129.721756956142, 11339.433028348714, 11392.08863290918, 10946.507407166537, 11031.613893513688, 11160.218784684994, 11234.438278086227, 11332.433378331216, 9198.448093114435, 11079.501422436171, 11172.273802202832, 11371.43142842871, 11403.714907127056, 7820.139784623573, 7884.763457096296, 8269.255766981001, 8306.584670766559, 8372.539510326991, 9741.659041933499, 10009.599616015277, 10255.7436064099, 10388.532516036948, 11085.501152447998], "value": 9485.44545815882, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1475.4909556203113, 1510.380743895003, 1676.4383931382986, 1805.7742782152231, 1847.5104097414187, 7897.723579674705, 8354.665813367457, 8356.582170891446, 8359.540225287621, 8365.12166222547, 7346.449016323771, 7591.392688584914, 7593.772186834404, 7680.539167649945, 8156.551389673566, 2075.875447473149, 2107.062357251024, 2222.29997550771, 2291.472961218921, 2304.781045800646, 2110.662294032955, 2187.6718492226214, 2329.4292898239955, 2330.196082351586, 2332.148765700524, 2244.9102035918545, 2293.2776175504746, 2297.253392647388, 2299.6090664586977, 2302.343832007878, 2267.104493724986, 2272.170657709932, 2289.5993201189726, 2289.6336586146135, 2290.0, 2289.828262880274, 2295.644175152854, 2319.130326127702, 2321.466062805547, 2328.161861729776, 8199.508029518249, 8201.8359632807, 8267.917320826835, 8283.08886022537, 8310.501369917823, 5005.57452616528, 5151.227315902607, 5163.870903227412, 5170.095233334151, 5170.224466330043, 3200.7119359257545, 3409.232922592422, 3484.163800687842, 3487.2851065531504, 3510.701590364822, 1005.2962925951863, 1018.0684673523717, 1037.6005237983354, 1047.2564479219764, 1063.8351055586397, 10551.0, 10559.947200264027, 10721.571137154504, 10723.78552428955, 10829.83755243672, 4356.215881141362, 4508.481524624644, 4586.174488592019, 4587.17430862448, 4640.698354607007, 9270.44377337362, 9272.258219342437, 9283.814323713623, 9287.164155226063, 9294.163525282622, 5149.742512874342, 5151.175811870157, 5156.535911767959, 5157.793688252428, 5159.664621799571, 3984.382420724792, 4458.643308535309, 4688.718676879398, 4700.482946875841, 4701.976490117528, 2863.5704644303105, 3503.211777350076, 3543.2204914918675, 3630.8366123523974, 3738.4953031340965, 1239.7768401687783, 1240.1628900492133, 1248.095131030014, 1253.6489782860813, 1258.804885242789, 877.4603618774536, 882.1355072029447, 885.1767855893952, 898.2275243290765, 910.7085732565515, 5446.373667028342, 5481.561475081984, 5574.108142697149, 5594.328680558359, 5653.943460565424, 968.6658102954469, 982.2878413150552, 1010.242318261304, 1031.1080915008613, 1040.9739756506149, 3710.3135919854913, 3762.360398732222, 3780.7353485256317, 3850.4994350734787, 3895.2209558088493, 9636.46999415039, 9913.553890074963, 10016.248781341428, 10022.849657255147, 10056.0, 9980.750481238027, 9981.45102019396, 9981.650642227632, 9985.550650220757, 9986.850197247048, 7561.848763024819, 7682.846343073219, 7743.612819359009, 7853.568053756984, 7894.289513943658, 5328.014317351302, 5354.9464505354445, 5477.643953143033, 5493.862653433696, 5559.193916881949, 7836.882446763417, 9293.86059209126, 9374.578143983674, 9379.437233765995, 9419.62321507132, 8982.416142950688, 9354.111359420844, 9376.95311523438, 9395.953020234854, 9424.575894084646, 6949.131358580177, 7116.822079447848, 7121.109861267341, 7125.82185445368, 7148.463865210234, 6938.306169383021, 7077.433805295667, 7197.0, 7630.8855367170645, 7735.613219339123, 6622.867542649024, 6822.897656535255, 7062.823429414306, 7094.148702155568, 7117.288271172842, 6893.4140598047725, 6941.167059952838, 6946.8610627788175, 6953.374196322356, 6991.685374158277, 6494.512911531559, 6628.966855165886, 6634.800955971328, 6639.3692599203, 6639.568428052161, 4566.520515345841, 4668.323093151542, 4668.556487133853, 4808.3027960946165, 4810.11012962603, 4118.773467459198, 4733.479317275166, 4755.714657120584, 4760.59534939534, 4945.925811112908], "value": 4358.028261834084, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.1327377153797, 303.5128618567201, 308.5233314529053, 314.7654997027215, 315.4668610049017, 5186.870328241791, 5251.321825868806, 5262.315898933143, 5311.636321836784, 5347.7058761768085, 4159.66722662187, 4439.356293337472, 4707.78814953327, 4894.402614078658, 4912.435069966955, 3554.111472131967, 3799.031247032003, 4049.1496785675045, 4076.0217547788616, 4085.673146148301, 3361.96638033619, 4000.6999475039343, 4060.533038700543, 4073.816678249471, 4088.2232375848644, 3995.6603688686496, 4019.4171845082446, 4021.77880216588, 4028.2950483665386, 4053.209624123297, 3421.4354631486026, 3443.2424866529323, 3953.8138558432356, 4014.8193331300163, 4036.8183431745642, 2765.5575107982827, 2953.5126704094014, 3434.8797792077157, 3510.666486683761, 3544.291141771656, 4178.6657067434535, 5077.543021128116, 5081.720505372203, 5131.974340128313, 5150.587952963791, 3609.2961872434885, 4312.762798046107, 4498.651202449298, 4588.701734387288, 4636.887147084675, 4836.492168322311, 5489.0, 5546.805861794858, 5552.167174923753, 5571.275734154575, 3122.687731226882, 3238.9028329150165, 3843.5964223756664, 3860.5560360558616, 3893.143508428141, 4593.885152871171, 5336.946630533684, 5446.319210098738, 5698.800541981052, 5737.110747834093, 3763.5483741950593, 3824.655780979725, 3903.6291552302487, 3962.2075584883137, 3971.900702482461, 3964.484616999772, 3997.140614767839, 4002.9199416011515, 4003.979980100081, 4016.6184212499766, 4034.0, 4172.415861779354, 4541.75020373876, 4583.518730533313, 4650.674557751025, 4782.569568738831, 5536.307961504812, 5563.610547261653, 5581.49766521015, 5645.661260324394, 1196.4137572589543, 1236.3385588709998, 1292.31353084226, 1306.817045613615, 1312.2848047813964, 734.1520543771952, 829.788403956987, 833.9749807505784, 873.3362644390309, 898.0, 4080.2859499587453, 4516.548345165523, 4761.690490118201, 4820.998571185977, 4847.636427267969, 4472.814704103415, 4734.881627959328, 4917.852464426073, 5401.783928642889, 5430.321209848769, 4279.465066866642, 4335.349697545394, 4580.0076966519, 4841.394825646794, 4843.200871856175, 2235.6697764830224, 2328.9955956815857, 2332.2420213430364, 2455.7053153621678, 2824.5057115004793, 5096.190852247473, 5474.42518535556, 5497.257870187554, 5567.85637529546, 5573.916391254134, 5243.292155559027, 5310.4424035476495, 5459.426760190123, 5955.731992060281, 5972.880542389128, 4813.638977076734, 5722.771089156554, 6029.969850150721, 6033.849153771191, 6038.698065096815, 4873.707577545359, 5580.525655319343, 5783.479486846204, 5840.532757379315, 5857.9121313179485, 4589.406985053434, 4961.809165800109, 5308.8677680168885, 5554.833354999357, 5642.23829782974, 5188.325517682677, 5910.852228694317, 5955.166276721349, 5980.073088671263, 6091.994820854509, 3752.549694036733, 3849.461075449495, 3863.9760463477764, 3875.4304506674844, 3880.84476620932, 5042.899142017212, 5681.886362272814, 5746.683932383592, 5764.250647415809, 5769.94230057694, 5609.810924894375, 5613.915791263216, 5620.353659329229, 5625.803096891589, 5630.184907546227, 5280.419153892992, 5606.6636001840025, 5622.297212848394, 5640.869317528811, 5651.57613179005, 4806.639502037431, 5400.810971615974, 5481.890362192654, 5501.614886958035, 5541.2796336476, 4392.714473559208, 5064.12627327891, 5338.866528336823, 5435.537979271806, 5462.726863656721, 5443.237946687388, 5448.373437054789, 5547.583931205095, 5566.192902029264, 5577.581681373995], "value": 3976.430105449352, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.8936864785958, 710.0910834132309, 737.9852402951938, 763.6678045050403, 782.4327362662069, 13597.45610175593, 14176.574702758933, 14195.0, 14207.715845683093, 14231.501897433578, 12559.0, 12661.177023493467, 12767.170133941287, 12787.0, 12794.296313702742, 631.4569470255576, 648.9513286503508, 649.3279455763286, 650.7592190889378, 676.4824908944661, 646.227757829394, 649.6102338596851, 797.7407342613644, 822.5845947796355, 826.2687521543418, 851.5827244650138, 861.9525926074064, 865.9523726195057, 866.618687777376, 868.227277722824, 858.3433673239977, 860.3031544448985, 860.561113831944, 861.6251930410228, 862.310151878501, 498.6811327327918, 513.6019584821755, 515.1371452816518, 648.0, 649.3603800256743, 11250.49372778227, 12814.487420503168, 13373.0, 13378.0, 13740.450381984709, 13096.47614095435, 13359.465621375133, 13458.596242112751, 13483.258420786853, 13513.932430337883, 10612.0, 10953.83569246462, 11074.723131921686, 11101.611443599435, 11102.61140860066, 3650.7809531427934, 3656.597774244831, 3657.908552286214, 3667.523221981152, 3746.4567637692653, 83117.16882831215, 88273.11726882713, 89770.10229897682, 91169.08830911672, 91259.54370228107, 10289.022542858416, 10522.368657880552, 10527.21045921559, 10545.156387488982, 10649.094826939641, 20673.0, 20777.792222077587, 20912.268070617454, 20930.895345523175, 20971.895140524506, 6842.144731908512, 7200.855982880313, 7275.527090739085, 7300.379467745302, 7507.21174276704, 4071.959280407158, 4182.121754431603, 4255.3829694694095, 4333.523312435629, 4390.297552391602, 3271.2476130489645, 3372.359251742161, 3379.341028499419, 3611.602723700391, 3789.2232092420986, 651.8761435327273, 682.9726810927507, 690.5691964285686, 692.62944324786, 692.6675195905993, 637.7608396851181, 655.5083687234574, 663.8605892762574, 675.1965161457849, 691.5055735149392, 8623.78440538979, 8857.64569417229, 8858.512781796933, 9021.699466635966, 9183.265338772882, 597.333972620525, 621.8849512840139, 650.4763665249425, 657.9998402428282, 660.379243511098, 4353.216421044242, 4443.266860967904, 4444.3333499974615, 4470.307102399133, 4573.794179261941, 60627.69686151629, 76445.0, 85137.0, 91589.85772672089, 117424.65150696939, 66549.33626659276, 68530.65734671394, 103791.0, 103945.0, 104665.95334046562, 75437.6228118856, 92087.0, 92880.96273742185, 93856.0, 94704.57943130858, 8490.575471226537, 8565.743027709179, 8615.870761938451, 8661.090585488433, 8734.344924130588, 57818.39424152601, 58549.0, 58917.0, 58945.41054589399, 60360.396396035474, 53735.462645373045, 61892.38107619046, 62215.0, 82199.5890020546, 87312.0, 11813.822792657946, 11918.344491053076, 12035.45840437165, 12214.633560993185, 12682.809757853454, 12740.745185096432, 13299.0, 13834.100783449447, 14082.22547759842, 14636.121832690073, 12237.75524489523, 12498.187617804813, 13041.0, 13599.048066635241, 13814.171149731048, 12405.503779849067, 12469.750604987668, 12534.185277956904, 12738.87261127377, 13264.0, 11058.447077646246, 11392.430378480876, 12522.060845436446, 12924.353782310658, 13192.340382980621, 7765.844683106193, 10465.372077675363, 10526.421046842497, 11010.669679909615, 11098.667039988813, 10127.29108962395, 11123.833142502706, 11316.434178291218, 11382.089432845518, 11442.599509017478], "value": 7089.6392285628535, "unit": "score"}], "name": "dromaeo_css", "value": 7098.2626473691025, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 119.0, 164.0, 170.0, 114.0, 115.0, 115.0, 116.0, 114.0], "value": 125.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 115.0, 113.0, 149.0, 116.0, 118.0, 100.0, 122.0, 178.0, 143.0], "value": 130.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 184.0, 182.0, 183.0, 220.0, 264.0, 192.0, 183.0], "value": 196.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 72.0, 60.0, 89.0, 62.0, 59.0, 60.0, 59.0, 82.0, 67.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 97.0, 70.0, 68.0, 70.0, 90.0, 68.0, 69.0, 92.0], "value": 78.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 135.0, 131.0, 89.0, 85.0, 90.0, 87.0, 90.0, 88.0], "value": 97.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 98.0, 106.0, 163.0, 168.0, 97.0, 101.0, 97.0, 97.0, 98.0], "value": 112.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 79.0, 79.0, 82.0, 80.0, 84.0, 85.0, 81.0, 83.0], "value": 81.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 53.0, 55.0, 57.0, 58.0, 58.0, 57.0, 58.0, 58.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 67.0, 76.0, 75.0, 68.0, 69.0, 85.0, 102.0, 104.0], "value": 78.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 88.0, 86.0, 85.0, 83.0, 108.0, 101.0, 104.0, 82.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 146.0, 147.0, 150.0, 142.0, 174.0, 162.0, 227.0, 164.0], "value": 160.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [230.0, 228.0, 173.0, 169.0, 171.0, 168.0, 166.0, 171.0, 166.0, 181.0], "value": 182.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 67.0, 67.0, 68.0, 66.0, 64.0, 66.0, 66.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1526.6000000000001, "alertThreshold": 2.0}]} 22:44:21 INFO - Return code: 0 22:44:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:44:21 INFO - # TBPL SUCCESS # 22:44:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:44:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:44:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:44:21 INFO - 2016-11-29 22:44:21.614 screenresolution[1655:9846] starting screenresolution argv=screenresolution get 22:44:21 INFO - 2016-11-29 22:44:21.617 screenresolution[1655:9846] Display 0: 1600x1200x32@60 22:44:21 INFO - 2016-11-29 22:44:21.623 screenresolution[1656:9849] starting screenresolution argv=screenresolution list 22:44:21 INFO - Available Modes on Display 0 22:44:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:44:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:44:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:44:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:44:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:44:21 INFO - Intel Iris: 22:44:21 INFO - Chipset Model: Intel Iris 22:44:21 INFO - Type: GPU 22:44:21 INFO - Bus: Built-In 22:44:21 INFO - VRAM (Dynamic, Max): 1536 MB 22:44:21 INFO - Vendor: Intel (0x8086) 22:44:21 INFO - Device ID: 0x0a2e 22:44:21 INFO - Revision ID: 0x0009 22:44:21 INFO - Displays: 22:44:21 INFO - Display: 22:44:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:44:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:44:21 INFO - Display Serial Number: 1600x1200 60 22:44:21 INFO - Main Display: Yes 22:44:21 INFO - Mirror: Off 22:44:21 INFO - Online: Yes 22:44:21 INFO - Rotation: Supported 22:44:21 INFO - Return code: 0 22:44:21 INFO - Running post-action listener: _resource_record_post_action 22:44:21 INFO - [mozharness: 2016-11-30 06:44:21.873947Z] Finished run-tests step (success) 22:44:21 INFO - Running post-run listener: _resource_record_post_run 22:44:21 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37716992; Write bytes: 656691200; Read time: 238; Write time: 1883 22:44:21 INFO - TinderboxPrint: CPU usage
53.4% 22:44:21 INFO - TinderboxPrint: I/O read bytes / time
37,716,992 / 238 22:44:21 INFO - TinderboxPrint: I/O write bytes / time
656,691,200 / 1,883 22:44:21 INFO - TinderboxPrint: CPU idle
2,260.7 (46.6%) 22:44:21 INFO - TinderboxPrint: CPU system
972.2 (20.0%) 22:44:21 INFO - TinderboxPrint: CPU user
1,620.8 (33.4%) 22:44:21 INFO - TinderboxPrint: Swap in / out
386,297,856 / 0 22:44:21 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345805312; Write bytes: 344938496; Read time: 26887; Write time: 725 22:44:21 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 35722240; Write bytes: 304773120; Read time: 221; Write time: 1130 22:44:22 INFO - Running post-run listener: _upload_blobber_files 22:44:22 INFO - Blob upload gear active. 22:44:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:44:22 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 22:44:22 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'] 22:44:22 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 22:44:22 INFO - (blobuploader) - INFO - Open directory for files ... 22:44:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:44:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:44:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:23 INFO - (blobuploader) - INFO - Done attempting. 22:44:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:44:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:44:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:24 INFO - (blobuploader) - INFO - Done attempting. 22:44:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:44:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:44:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:26 INFO - (blobuploader) - INFO - Done attempting. 22:44:26 INFO - (blobuploader) - INFO - Iteration through files over. 22:44:26 INFO - Return code: 0 22:44:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:44:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:44:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c255d54c233b184189d2672d00942b72b5c33489ad3ea1016a50c620cf7fd9e47c4b20432f3d8ccb72332f438ced7a17c7ff48e1a6a619300c80df2f347b832a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d718dcdd01bbf3e056a52ea7e82fa1f78cd455aa46b8ebacb6de114880bbe3ad4980f872055e73230937d41947ee228c61ca0f6915cecf7b292330c8b331b6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d40e92e78c4e3bd30f41ce68fbb4c412c10f1e6cf873f1e58035748c34217b302e99d8cef93f1a02a6d6df9162fc59df84725f99229cee8509401f14d168038"} 22:44:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:44:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:44:26 INFO - Contents: 22:44:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c255d54c233b184189d2672d00942b72b5c33489ad3ea1016a50c620cf7fd9e47c4b20432f3d8ccb72332f438ced7a17c7ff48e1a6a619300c80df2f347b832a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d718dcdd01bbf3e056a52ea7e82fa1f78cd455aa46b8ebacb6de114880bbe3ad4980f872055e73230937d41947ee228c61ca0f6915cecf7b292330c8b331b6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d40e92e78c4e3bd30f41ce68fbb4c412c10f1e6cf873f1e58035748c34217b302e99d8cef93f1a02a6d6df9162fc59df84725f99229cee8509401f14d168038"} 22:44:26 INFO - Running post-run listener: copy_logs_to_upload_dir 22:44:26 INFO - Copying logs to upload dir... 22:44:26 INFO - mkdir: /builds/slave/test/build/upload/logs 22:44:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.192778 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-29 22:44:26.852163) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 22:44:26.856407) ========= 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.lyrXMDEQxl/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.Z03uutpdnm/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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/c255d54c233b184189d2672d00942b72b5c33489ad3ea1016a50c620cf7fd9e47c4b20432f3d8ccb72332f438ced7a17c7ff48e1a6a619300c80df2f347b832a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d718dcdd01bbf3e056a52ea7e82fa1f78cd455aa46b8ebacb6de114880bbe3ad4980f872055e73230937d41947ee228c61ca0f6915cecf7b292330c8b331b6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d40e92e78c4e3bd30f41ce68fbb4c412c10f1e6cf873f1e58035748c34217b302e99d8cef93f1a02a6d6df9162fc59df84725f99229cee8509401f14d168038"} build_url:https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011213 build_url: 'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/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/c255d54c233b184189d2672d00942b72b5c33489ad3ea1016a50c620cf7fd9e47c4b20432f3d8ccb72332f438ced7a17c7ff48e1a6a619300c80df2f347b832a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d718dcdd01bbf3e056a52ea7e82fa1f78cd455aa46b8ebacb6de114880bbe3ad4980f872055e73230937d41947ee228c61ca0f6915cecf7b292330c8b331b6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d40e92e78c4e3bd30f41ce68fbb4c412c10f1e6cf873f1e58035748c34217b302e99d8cef93f1a02a6d6df9162fc59df84725f99229cee8509401f14d168038"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 22:44:26.894673) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 22:44:26.898016) ========= 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.lyrXMDEQxl/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.Z03uutpdnm/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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.004915 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 22:44:26.950783) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 22:44:26.951130) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 22:44:26.951495) ========= ========= Total master_lag: 0.21 =========