builder: cedar_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0228 starttime: 1478131195.62 results: success (0) buildid: 20161102143123 builduid: d5bdbdd1399a43f28f50fb6619c15691 revision: ee7c1fd6966586f0ffab187d164b3e282f771cab ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-02 16:59:55.620793) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-02 16:59:55.621245) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-02 16:59:55.621531) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-02 16:59:55.721559) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 16:59:55.721825) ========= 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.RSLj4b7vUe/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.uNmwONNKyK/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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-02 16:59:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.4M=0.001s 2016-11-02 16:59:56 (18.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.460272 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 16:59:56.203330) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 16:59:56.203638) ========= 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.RSLj4b7vUe/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.uNmwONNKyK/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.025237 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 16:59:56.252505) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-02 16:59:56.252895) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev ee7c1fd6966586f0ffab187d164b3e282f771cab --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev ee7c1fd6966586f0ffab187d164b3e282f771cab --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RSLj4b7vUe/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.uNmwONNKyK/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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-02 16:59:56,340 truncating revision to first 12 chars 2016-11-02 16:59:56,340 Setting DEBUG logging. 2016-11-02 16:59:56,340 attempt 1/10 2016-11-02 16:59:56,340 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/ee7c1fd69665?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-02 16:59:57,588 unpacking tar archive at: cedar-ee7c1fd69665/testing/mozharness/ program finished with exit code 0 elapsedTime=1.490644 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-02 16:59:57.764877) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-02 16:59:57.765224) ========= script_repo_revision: ee7c1fd6966586f0ffab187d164b3e282f771cab ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-02 16:59:57.765624) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-02 16:59:57.766046) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-02 16:59:57.785444) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-02 16:59:57.785802) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RSLj4b7vUe/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.uNmwONNKyK/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 16:59:57 INFO - MultiFileLogger online at 20161102 16:59:57 in /builds/slave/test 16:59:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 16:59:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:59:57 INFO - {'append_to_log': False, 16:59:57 INFO - 'base_work_dir': '/builds/slave/test', 16:59:57 INFO - 'blob_upload_branch': 'Cedar', 16:59:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:59:57 INFO - 'branch': 'Cedar', 16:59:57 INFO - 'buildbot_json_path': 'buildprops.json', 16:59:57 INFO - 'config_files': ('talos/mac_config.py',), 16:59:57 INFO - 'default_actions': ('clobber', 16:59:57 INFO - 'read-buildbot-config', 16:59:57 INFO - 'download-and-extract', 16:59:57 INFO - 'populate-webroot', 16:59:57 INFO - 'create-virtualenv', 16:59:57 INFO - 'install', 16:59:57 INFO - 'run-tests'), 16:59:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:59:57 INFO - 'download_minidump_stackwalk': True, 16:59:57 INFO - 'download_symbols': 'ondemand', 16:59:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:59:57 INFO - 'tooltool.py': '/tools/tooltool.py', 16:59:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:59:57 INFO - '/tools/misc-python/virtualenv.py')}, 16:59:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:59:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:59:57 INFO - 'installer_path': 'installer.exe', 16:59:57 INFO - 'log_level': 'info', 16:59:57 INFO - 'log_name': 'talos', 16:59:57 INFO - 'log_to_console': True, 16:59:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:59:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:59:57 INFO - 'opt_config_files': (), 16:59:57 INFO - 'pip_index': False, 16:59:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:59:57 INFO - 'cmd': ('bash', 16:59:57 INFO - '-c', 16:59:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:59:57 INFO - 'enabled': True, 16:59:57 INFO - 'halt_on_failure': False, 16:59:57 INFO - 'name': 'check_screen_resolution'},), 16:59:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:59:57 INFO - 'cmd': ('bash', 16:59:57 INFO - '-c', 16:59:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:59:57 INFO - 'enabled': True, 16:59:57 INFO - 'halt_on_failure': False, 16:59:57 INFO - 'name': 'check_screen_resolution'},), 16:59:57 INFO - 'run_cmd_checks_enabled': True, 16:59:57 INFO - 'sps_profile': False, 16:59:57 INFO - 'sps_profile_interval': 0, 16:59:57 INFO - 'suite': 'dromaeojs', 16:59:57 INFO - 'system_bits': '32', 16:59:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:59:57 INFO - 'title': 't-yosemite-r7-0228', 16:59:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:59:57 INFO - 'use_talos_json': True, 16:59:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:59:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:59:57 INFO - 'work_dir': 'build'} 16:59:57 INFO - [mozharness: 2016-11-02 23:59:57.993039Z] Running clobber step. 16:59:57 INFO - Running pre-action listener: _resource_record_pre_action 16:59:57 INFO - Running main action method: clobber 16:59:57 INFO - rmtree: /builds/slave/test/build 16:59:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:00:00 INFO - Running post-action listener: _resource_record_post_action 17:00:00 INFO - [mozharness: 2016-11-03 00:00:00.664245Z] Finished clobber step (success) 17:00:00 INFO - [mozharness: 2016-11-03 00:00:00.664507Z] Running read-buildbot-config step. 17:00:00 INFO - Running pre-action listener: _resource_record_pre_action 17:00:00 INFO - Running main action method: read_buildbot_config 17:00:00 INFO - Using buildbot properties: 17:00:00 INFO - { 17:00:00 INFO - "project": "", 17:00:00 INFO - "product": "firefox", 17:00:00 INFO - "script_repo_revision": "default", 17:00:00 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs", 17:00:00 INFO - "repository": "", 17:00:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs", 17:00:00 INFO - "buildid": "20161102143123", 17:00:00 INFO - "pgo_build": "False", 17:00:00 INFO - "basedir": "/builds/slave/test", 17:00:00 INFO - "buildnumber": 0, 17:00:00 INFO - "slavename": "t-yosemite-r7-0228", 17:00:00 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 17:00:00 INFO - "platform": "yosemite_r7", 17:00:00 INFO - "scheduler": "tests-cedar-macosx64-talos", 17:00:00 INFO - "branch": "cedar", 17:00:00 INFO - "repo_path": "projects/cedar", 17:00:00 INFO - "revision": "ee7c1fd6966586f0ffab187d164b3e282f771cab", 17:00:00 INFO - "stage_platform": "macosx64", 17:00:00 INFO - "builduid": "d5bdbdd1399a43f28f50fb6619c15691", 17:00:00 INFO - "slavebuilddir": "test" 17:00:00 INFO - } 17:00:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 17:00:00 INFO - Running post-action listener: _resource_record_post_action 17:00:00 INFO - [mozharness: 2016-11-03 00:00:00.670070Z] Finished read-buildbot-config step (success) 17:00:00 INFO - [mozharness: 2016-11-03 00:00:00.670282Z] Running download-and-extract step. 17:00:00 INFO - Running pre-action listener: _resource_record_pre_action 17:00:00 INFO - Running main action method: download_and_extract 17:00:00 INFO - mkdir: /builds/slave/test/build/tests 17:00:00 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')]} 17:00:00 INFO - trying https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 17:00:00 INFO - Downloading https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 17:00:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 17:00:02 INFO - Downloaded 1199 bytes. 17:00:02 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 17:00:02 INFO - Using the following test package requirements: 17:00:02 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:00:02 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:00:02 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 17:00:02 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:00:02 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 17:00:02 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:00:02 INFO - u'jsshell-mac.zip'], 17:00:02 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:00:02 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 17:00:02 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:00:02 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:00:02 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 17:00:02 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:00:02 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 17:00:02 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:00:02 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 17:00:02 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:00:02 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 17:00:02 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:00:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:00:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:00:02 INFO - Fetch https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:00:02 INFO - Http code: 200 17:00:02 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 17:00:02 INFO - x-amz-cf-id: 7e-N4jsfpl_6t-bN8J0kSox5Gm81bt6QkKKpRoXZQaGubXtHXPxBBg== 17:00:02 INFO - x-amz-version-id: wpYy30.zjx2SC511amlLzinVLbyjYV7k 17:00:03 INFO - Expected file size: 16404885 17:00:03 INFO - Obtained file size: 16404885 17:00:09 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:00:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:00:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:00:09 INFO - Fetch https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:00:10 INFO - Http code: 200 17:00:10 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 17:00:10 INFO - x-amz-cf-id: 1NsklVxtSKI4nuwTD5woRFB6D40JuTZkOx_c_0EVr1N9NTTRUr4KpA== 17:00:10 INFO - x-amz-version-id: wpYy30.zjx2SC511amlLzinVLbyjYV7k 17:00:10 INFO - Expected file size: 16404885 17:00:10 INFO - Obtained file size: 16404885 17:00:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 17:00:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:00:16 INFO - Fetch https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 17:00:17 INFO - Http code: 200 17:00:17 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 17:00:17 INFO - x-amz-cf-id: zhrmtEZI705ZJU5A_72HngVHdIC3yQFdc174t2kFl_-L-0Yl5fDuBw== 17:00:17 INFO - x-amz-version-id: K.zgK0nvn1AexO8yAEZiC6XAHAu2Koqe 17:00:17 INFO - Expected file size: 14409038 17:00:17 INFO - Obtained file size: 14409038 17:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:18 INFO - trying https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:00:18 INFO - Downloading https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 17:00:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 17:00:21 INFO - Downloaded 112096987 bytes. 17:00:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:00:21 INFO - mkdir: /builds/slave/test/properties 17:00:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:00:21 INFO - Writing to file /builds/slave/test/properties/build_url 17:00:21 INFO - Contents: 17:00:21 INFO - build_url:https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:00:22 INFO - Running post-action listener: _resource_record_post_action 17:00:22 INFO - Running post-action listener: set_extra_try_arguments 17:00:22 INFO - [mozharness: 2016-11-03 00:00:22.297059Z] Finished download-and-extract step (success) 17:00:22 INFO - [mozharness: 2016-11-03 00:00:22.297163Z] Running populate-webroot step. 17:00:22 INFO - Running pre-action listener: _resource_record_pre_action 17:00:22 INFO - Running main action method: populate_webroot 17:00:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:00:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:00:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:00:22 INFO - '--tppagecycles', 17:00:22 INFO - '7'], 17:00:22 INFO - 'tests': ['tsvgm']}}, 17:00:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:00:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:00:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:00:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:00:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:00:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:00:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:22 INFO - 'talos_options': ['--disable-e10s'], 17:00:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:00:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:00:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:22 INFO - 'talos_options': ['--disable-e10s'], 17:00:22 INFO - 'tests': ['damp', 'tps']}, 17:00:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:22 INFO - 'tests': ['damp', 'tps']}, 17:00:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:00:22 INFO - 'tests': ['dromaeo_dom']}, 17:00:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:00:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:00:22 INFO - 'tests': ['basic_compositor_video']}, 17:00:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:00:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:00:22 INFO - 'tests': ['a11yr', 17:00:22 INFO - 'ts_paint', 17:00:22 INFO - 'tpaint', 17:00:22 INFO - 'sessionrestore', 17:00:22 INFO - 'sessionrestore_no_auto_restore', 17:00:22 INFO - 'tabpaint']}, 17:00:22 INFO - 'other-e10s': {'tests': ['a11yr', 17:00:22 INFO - 'ts_paint', 17:00:22 INFO - 'tpaint', 17:00:22 INFO - 'sessionrestore', 17:00:22 INFO - 'sessionrestore_no_auto_restore', 17:00:22 INFO - 'tabpaint']}, 17:00:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:00:22 INFO - 'ts_paint', 17:00:22 INFO - 'tpaint', 17:00:22 INFO - 'sessionrestore', 17:00:22 INFO - 'sessionrestore_no_auto_restore', 17:00:22 INFO - 'tabpaint']}, 17:00:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:00:22 INFO - 'ts_paint', 17:00:22 INFO - 'tpaint', 17:00:22 INFO - 'sessionrestore', 17:00:22 INFO - 'sessionrestore_no_auto_restore', 17:00:22 INFO - 'tabpaint']}, 17:00:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:00:22 INFO - 'tests': ['a11yr', 17:00:22 INFO - 'ts_paint', 17:00:22 INFO - 'tpaint', 17:00:22 INFO - 'sessionrestore', 17:00:22 INFO - 'sessionrestore_no_auto_restore', 17:00:22 INFO - 'tabpaint']}, 17:00:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:00:22 INFO - 'tests': ['a11yr', 17:00:22 INFO - 'ts_paint', 17:00:22 INFO - 'tpaint', 17:00:22 INFO - 'sessionrestore', 17:00:22 INFO - 'sessionrestore_no_auto_restore', 17:00:22 INFO - 'tabpaint']}, 17:00:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:00:22 INFO - 'tests': ['tsvgx', 17:00:22 INFO - 'tsvgr_opacity', 17:00:22 INFO - 'tart', 17:00:22 INFO - 'tscrollx', 17:00:22 INFO - 'cart']}, 17:00:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:00:22 INFO - 'tsvgr_opacity', 17:00:22 INFO - 'tart', 17:00:22 INFO - 'tscrollx', 17:00:22 INFO - 'cart']}, 17:00:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:22 INFO - 'talos_options': ['--disable-e10s'], 17:00:22 INFO - 'tests': ['tp5o']}, 17:00:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:22 INFO - 'tests': ['tp5o']}, 17:00:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:22 INFO - 'talos_options': ['--disable-e10s', 17:00:22 INFO - '--xperf_path', 17:00:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:00:22 INFO - 'tests': ['tp5n']}, 17:00:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:22 INFO - 'talos_options': ['--xperf_path', 17:00:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:00:22 INFO - 'tests': ['tp5n']}}, 17:00:22 INFO - 'talos.zip': {'path': '', 17:00:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:00:22 INFO - Running post-action listener: _resource_record_post_action 17:00:22 INFO - [mozharness: 2016-11-03 00:00:22.315865Z] Finished populate-webroot step (success) 17:00:22 INFO - [mozharness: 2016-11-03 00:00:22.315980Z] Running create-virtualenv step. 17:00:22 INFO - Running pre-action listener: _resource_record_pre_action 17:00:22 INFO - Running main action method: create_virtualenv 17:00:22 INFO - Creating virtualenv /builds/slave/test/build/venv 17:00:22 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 17:00:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:00:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:00:22 INFO - Using real prefix '/tools/python27' 17:00:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:00:23 INFO - Installing distribute.............................................................................................................................................................................................done. 17:00:25 INFO - Installing pip.................done. 17:00:25 INFO - Return code: 0 17:00:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:00:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:00:26 INFO - Reading from file tmpfile_stdout 17:00:26 INFO - Output received: 17:00:26 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) 17:00:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110530490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb05248e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110344d30>, '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 0x11016adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11033cb20>, '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.RSLj4b7vUe/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.uNmwONNKyK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 17:00:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:00:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSLj4b7vUe/Render', 17:00:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:26 INFO - 'HOME': '/Users/cltbld', 17:00:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:26 INFO - 'LOGNAME': 'cltbld', 17:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:26 INFO - 'MOZ_NO_REMOTE': '1', 17:00:26 INFO - 'NO_EM_RESTART': '1', 17:00:26 INFO - 'PAGER': '/bin/cat', 17:00:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:26 INFO - 'PWD': '/builds/slave/test', 17:00:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:26 INFO - 'SHELL': '/bin/bash', 17:00:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNmwONNKyK/Listeners', 17:00:26 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 17:00:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:26 INFO - 'USER': 'cltbld', 17:00:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:26 INFO - 'XPC_FLAGS': '0x0', 17:00:26 INFO - 'XPC_SERVICE_NAME': '0', 17:00:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:26 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 17:00:26 INFO - Cleaning up... 17:00:26 INFO - Return code: 0 17:00:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110530490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb05248e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110344d30>, '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 0x11016adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11033cb20>, '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.RSLj4b7vUe/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.uNmwONNKyK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 17:00:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:00:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSLj4b7vUe/Render', 17:00:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:26 INFO - 'HOME': '/Users/cltbld', 17:00:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:26 INFO - 'LOGNAME': 'cltbld', 17:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:26 INFO - 'MOZ_NO_REMOTE': '1', 17:00:26 INFO - 'NO_EM_RESTART': '1', 17:00:26 INFO - 'PAGER': '/bin/cat', 17:00:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:26 INFO - 'PWD': '/builds/slave/test', 17:00:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:26 INFO - 'SHELL': '/bin/bash', 17:00:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNmwONNKyK/Listeners', 17:00:26 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 17:00:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:26 INFO - 'USER': 'cltbld', 17:00:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:26 INFO - 'XPC_FLAGS': '0x0', 17:00:26 INFO - 'XPC_SERVICE_NAME': '0', 17:00:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:26 INFO - Downloading/unpacking psutil>=3.1.1 17:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:00:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:00:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:00:30 INFO - Installing collected packages: psutil 17:00:30 INFO - Running setup.py install for psutil 17:00:30 INFO - building 'psutil._psutil_osx' extension 17:00:30 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 17:00:31 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 17:00:31 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 17:00:31 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 17:00:31 INFO - building 'psutil._psutil_posix' extension 17:00:31 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 17:00:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:00:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:00:31 INFO - ^ 17:00:31 INFO - 1 warning generated. 17:00:31 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 17:00:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:00:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:00:31 INFO - Successfully installed psutil 17:00:31 INFO - Cleaning up... 17:00:31 INFO - Return code: 0 17:00:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:00: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')]} 17:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00: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')]} 17:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110530490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb05248e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110344d30>, '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 0x11016adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11033cb20>, '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.RSLj4b7vUe/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.uNmwONNKyK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 17:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:00:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSLj4b7vUe/Render', 17:00:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:31 INFO - 'HOME': '/Users/cltbld', 17:00:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:31 INFO - 'LOGNAME': 'cltbld', 17:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:31 INFO - 'MOZ_NO_REMOTE': '1', 17:00:31 INFO - 'NO_EM_RESTART': '1', 17:00:31 INFO - 'PAGER': '/bin/cat', 17:00:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:31 INFO - 'PWD': '/builds/slave/test', 17:00:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:31 INFO - 'SHELL': '/bin/bash', 17:00:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNmwONNKyK/Listeners', 17:00:31 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 17:00:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:31 INFO - 'USER': 'cltbld', 17:00:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:31 INFO - 'XPC_FLAGS': '0x0', 17:00:31 INFO - 'XPC_SERVICE_NAME': '0', 17:00:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:00:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:00:34 INFO - Installing collected packages: mozsystemmonitor 17:00:34 INFO - Running setup.py install for mozsystemmonitor 17:00:34 INFO - Successfully installed mozsystemmonitor 17:00:34 INFO - Cleaning up... 17:00:34 INFO - Return code: 0 17:00:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:00:34 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')]} 17:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:34 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')]} 17:00:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:34 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 0x110530490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb05248e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110344d30>, '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 0x11016adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11033cb20>, '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.RSLj4b7vUe/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.uNmwONNKyK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 17:00:34 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 17:00:34 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 17:00:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSLj4b7vUe/Render', 17:00:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:34 INFO - 'HOME': '/Users/cltbld', 17:00:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:34 INFO - 'LOGNAME': 'cltbld', 17:00:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:34 INFO - 'MOZ_NO_REMOTE': '1', 17:00:34 INFO - 'NO_EM_RESTART': '1', 17:00:34 INFO - 'PAGER': '/bin/cat', 17:00:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:34 INFO - 'PWD': '/builds/slave/test', 17:00:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:34 INFO - 'SHELL': '/bin/bash', 17:00:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNmwONNKyK/Listeners', 17:00:34 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 17:00:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:34 INFO - 'USER': 'cltbld', 17:00:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:34 INFO - 'XPC_FLAGS': '0x0', 17:00:34 INFO - 'XPC_SERVICE_NAME': '0', 17:00:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:35 INFO - Downloading/unpacking jsonschema==2.5.1 17:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:00:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:00:37 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 17:00:37 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 17:00:37 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 17:00:37 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 17:00:37 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 17:00:37 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 17:00:38 INFO - Downloading functools32-3.2.3-2.tar.gz 17:00:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:00:38 INFO - warning: no files found matching '*.txt' 17:00:38 INFO - no previously-included directories found matching 'build' 17:00:38 INFO - no previously-included directories found matching 'dist' 17:00:38 INFO - no previously-included directories found matching '.git*' 17:00:38 INFO - Installing collected packages: jsonschema, functools32 17:00:38 INFO - Running setup.py install for functools32 17:00:38 INFO - warning: no files found matching '*.txt' 17:00:38 INFO - no previously-included directories found matching 'build' 17:00:38 INFO - no previously-included directories found matching 'dist' 17:00:38 INFO - no previously-included directories found matching '.git*' 17:00:38 INFO - Successfully installed jsonschema functools32 17:00:38 INFO - Cleaning up... 17:00:38 INFO - Return code: 0 17:00:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:00:38 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')]} 17:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:38 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')]} 17:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:38 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 0x110530490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb05248e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110344d30>, '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 0x11016adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11033cb20>, '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.RSLj4b7vUe/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.uNmwONNKyK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 17:00:38 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 17:00:38 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 17:00:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSLj4b7vUe/Render', 17:00:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:38 INFO - 'HOME': '/Users/cltbld', 17:00:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:38 INFO - 'LOGNAME': 'cltbld', 17:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:38 INFO - 'MOZ_NO_REMOTE': '1', 17:00:38 INFO - 'NO_EM_RESTART': '1', 17:00:38 INFO - 'PAGER': '/bin/cat', 17:00:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:38 INFO - 'PWD': '/builds/slave/test', 17:00:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:38 INFO - 'SHELL': '/bin/bash', 17:00:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNmwONNKyK/Listeners', 17:00:38 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 17:00:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:38 INFO - 'USER': 'cltbld', 17:00:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:38 INFO - 'XPC_FLAGS': '0x0', 17:00:38 INFO - 'XPC_SERVICE_NAME': '0', 17:00:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:00:38 INFO - Cleaning up... 17:00:38 INFO - Return code: 0 17:00:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:00:38 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')]} 17:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:38 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')]} 17:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:38 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 0x110530490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb05248e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110344d30>, '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 0x11016adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11033cb20>, '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.RSLj4b7vUe/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.uNmwONNKyK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 17:00:38 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 17:00:38 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 17:00:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSLj4b7vUe/Render', 17:00:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:38 INFO - 'HOME': '/Users/cltbld', 17:00:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:38 INFO - 'LOGNAME': 'cltbld', 17:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:38 INFO - 'MOZ_NO_REMOTE': '1', 17:00:38 INFO - 'NO_EM_RESTART': '1', 17:00:38 INFO - 'PAGER': '/bin/cat', 17:00:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:38 INFO - 'PWD': '/builds/slave/test', 17:00:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:38 INFO - 'SHELL': '/bin/bash', 17:00:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNmwONNKyK/Listeners', 17:00:38 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 17:00:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:38 INFO - 'USER': 'cltbld', 17:00:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:38 INFO - 'XPC_FLAGS': '0x0', 17:00:38 INFO - 'XPC_SERVICE_NAME': '0', 17:00:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:38 INFO - Downloading/unpacking blobuploader==1.2.4 17:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:41 INFO - Downloading blobuploader-1.2.4.tar.gz 17:00:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:00:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:00:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:00:42 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 17:00:42 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 17:00:42 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 17:00:42 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 17:00:42 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 17:00:42 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 17:00:42 INFO - Downloading docopt-0.6.1.tar.gz 17:00:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:00:42 INFO - Installing collected packages: blobuploader, requests, docopt 17:00:42 INFO - Running setup.py install for blobuploader 17:00:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:00:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:00:42 INFO - Running setup.py install for requests 17:00:43 INFO - Running setup.py install for docopt 17:00:43 INFO - Successfully installed blobuploader requests docopt 17:00:43 INFO - Cleaning up... 17:00:43 INFO - Return code: 0 17:00:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:43 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 0x110530490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb05248e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110344d30>, '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 0x11016adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11033cb20>, '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.RSLj4b7vUe/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.uNmwONNKyK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 17:00:43 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 17:00:43 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 17:00:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSLj4b7vUe/Render', 17:00:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:43 INFO - 'HOME': '/Users/cltbld', 17:00:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:43 INFO - 'LOGNAME': 'cltbld', 17:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:43 INFO - 'MOZ_NO_REMOTE': '1', 17:00:43 INFO - 'NO_EM_RESTART': '1', 17:00:43 INFO - 'PAGER': '/bin/cat', 17:00:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:43 INFO - 'PWD': '/builds/slave/test', 17:00:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:43 INFO - 'SHELL': '/bin/bash', 17:00:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNmwONNKyK/Listeners', 17:00:43 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 17:00:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:43 INFO - 'USER': 'cltbld', 17:00:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:43 INFO - 'XPC_FLAGS': '0x0', 17:00:43 INFO - 'XPC_SERVICE_NAME': '0', 17:00:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:00:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-BffEN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:00:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-7jJ1y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:00:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-PAZDwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:00:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-vSO53i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:00:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-2SLnx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:00:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-V4GdVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:00:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-WRPhtn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:00:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-WjLKxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:00:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-MZgm0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:00:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-RYr_H4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:00:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-k8MJJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:00:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-F9eUuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:00:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-SyusT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:00:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-8p1Pwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:00:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-u_DAcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:00:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Uo8sCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:00:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-99V9w6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:00:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:00:45 INFO - Running setup.py install for manifestparser 17:00:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:00:45 INFO - Running setup.py install for mozcrash 17:00:45 INFO - Running setup.py install for mozdebug 17:00:46 INFO - Running setup.py install for mozdevice 17:00:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:00:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:00:46 INFO - Running setup.py install for mozfile 17:00:46 INFO - Running setup.py install for mozhttpd 17:00:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:00:46 INFO - Running setup.py install for mozinfo 17:00:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:00:46 INFO - Running setup.py install for mozInstall 17:00:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:00:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:00:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:00:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:00:46 INFO - Running setup.py install for mozleak 17:00:46 INFO - Running setup.py install for mozlog 17:00:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:00:47 INFO - Running setup.py install for moznetwork 17:00:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:00:47 INFO - Running setup.py install for mozprocess 17:00:47 INFO - Running setup.py install for mozprofile 17:00:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:00:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:00:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:00:47 INFO - Running setup.py install for mozrunner 17:00:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:00:47 INFO - Running setup.py install for mozscreenshot 17:00:47 INFO - Running setup.py install for moztest 17:00:48 INFO - Running setup.py install for mozversion 17:00:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:00:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:00:48 INFO - Cleaning up... 17:00:48 INFO - Return code: 0 17:00:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:00:48 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')]} 17:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:48 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')]} 17:00:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:48 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 0x110530490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb05248e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110344d30>, '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 0x11016adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11033cb20>, '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.RSLj4b7vUe/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.uNmwONNKyK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 17:00:48 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 17:00:48 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 17:00:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSLj4b7vUe/Render', 17:00:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:48 INFO - 'HOME': '/Users/cltbld', 17:00:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:48 INFO - 'LOGNAME': 'cltbld', 17:00:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:48 INFO - 'MOZ_NO_REMOTE': '1', 17:00:48 INFO - 'NO_EM_RESTART': '1', 17:00:48 INFO - 'PAGER': '/bin/cat', 17:00:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:48 INFO - 'PWD': '/builds/slave/test', 17:00:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:48 INFO - 'SHELL': '/bin/bash', 17:00:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNmwONNKyK/Listeners', 17:00:48 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 17:00:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:48 INFO - 'USER': 'cltbld', 17:00:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:48 INFO - 'XPC_FLAGS': '0x0', 17:00:48 INFO - 'XPC_SERVICE_NAME': '0', 17:00:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:00:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-60g4iI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:00:48 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)) 17:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:00:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-CwOlxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:00:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 17:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:00:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-AO1CCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:00:48 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)) 17:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:00:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-t_UpPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:00:48 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)) 17:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:00:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ouUlAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:00:49 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)) 17:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:00:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-0Wwx4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:00:49 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)) 17:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:00:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-EjlGBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:00:49 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)) 17:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:00:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-gIV9ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:00:49 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)) 17:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:00:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-yGxkaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:00:49 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)) 17:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:00:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Q2XvEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:00:49 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)) 17:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:00:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-IidWL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:00:49 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)) 17:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:00:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-D1LnYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:00:49 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)) 17:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:00:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-UeqXrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:00:49 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)) 17:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:00:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Zl4u24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 17:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:00:50 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-GPeusA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:00:50 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)) 17:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:00:50 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-xgovIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:00:50 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)) 17:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:00:50 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-i9j4FA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:00:50 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)) 17:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:00:50 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)) 17:00:50 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)) 17:00:50 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)) 17:00:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:00: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 17:00: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 17:00: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 17:00: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 17:00: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 17:00: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 17:00:52 INFO - Downloading blessings-1.6.tar.gz 17:00:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:00:52 INFO - Installing collected packages: blessings 17:00:52 INFO - Running setup.py install for blessings 17:00:53 INFO - Successfully installed blessings 17:00:53 INFO - Cleaning up... 17:00:53 INFO - Return code: 0 17:00:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:00:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:00:53 INFO - Reading from file tmpfile_stdout 17:00:53 INFO - Current package versions: 17:00:53 INFO - blessings == 1.6 17:00:53 INFO - blobuploader == 1.2.4 17:00:53 INFO - docopt == 0.6.1 17:00:53 INFO - functools32 == 3.2.3-2 17:00:53 INFO - jsonschema == 2.5.1 17:00:53 INFO - manifestparser == 1.1 17:00:53 INFO - mozInstall == 1.12 17:00:53 INFO - mozcrash == 0.17 17:00:53 INFO - mozdebug == 0.1 17:00:53 INFO - mozdevice == 0.48 17:00:53 INFO - mozfile == 1.2 17:00:53 INFO - mozhttpd == 0.7 17:00:53 INFO - mozinfo == 0.9 17:00:53 INFO - mozleak == 0.1 17:00:53 INFO - mozlog == 3.4 17:00:53 INFO - moznetwork == 0.27 17:00:53 INFO - mozprocess == 0.23 17:00:53 INFO - mozprofile == 0.28 17:00:53 INFO - mozrunner == 6.12 17:00:53 INFO - mozscreenshot == 0.1 17:00:53 INFO - mozsystemmonitor == 0.3 17:00:53 INFO - moztest == 0.7 17:00:53 INFO - mozversion == 1.4 17:00:53 INFO - psutil == 3.1.1 17:00:53 INFO - requests == 1.2.3 17:00:53 INFO - wsgiref == 0.1.2 17:00:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:53 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 0x110530490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb05248e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110344d30>, '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 0x11016adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11033cb20>, '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.RSLj4b7vUe/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.uNmwONNKyK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 17:00:53 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 17:00:53 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 17:00:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSLj4b7vUe/Render', 17:00:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:53 INFO - 'HOME': '/Users/cltbld', 17:00:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:53 INFO - 'LOGNAME': 'cltbld', 17:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:53 INFO - 'MOZ_NO_REMOTE': '1', 17:00:53 INFO - 'NO_EM_RESTART': '1', 17:00:53 INFO - 'PAGER': '/bin/cat', 17:00:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:53 INFO - 'PWD': '/builds/slave/test', 17:00:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:53 INFO - 'SHELL': '/bin/bash', 17:00:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNmwONNKyK/Listeners', 17:00:53 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 17:00:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:53 INFO - 'USER': 'cltbld', 17:00:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:53 INFO - 'XPC_FLAGS': '0x0', 17:00:53 INFO - 'XPC_SERVICE_NAME': '0', 17:00:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:53 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)) 17:00:53 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)) 17:00:53 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)) 17:00:53 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)) 17:00:53 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)) 17:00:53 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)) 17:00:53 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)) 17:00:53 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)) 17:00:53 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)) 17:00:53 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)) 17:00:53 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)) 17:00:53 INFO - Cleaning up... 17:00:53 INFO - Return code: 0 17:00:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:53 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 0x110530490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb05248e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110344d30>, '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 0x11016adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11033cb20>, '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.RSLj4b7vUe/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.uNmwONNKyK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 17:00:53 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 17:00:53 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 17:00:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSLj4b7vUe/Render', 17:00:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:53 INFO - 'HOME': '/Users/cltbld', 17:00:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:53 INFO - 'LOGNAME': 'cltbld', 17:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:53 INFO - 'MOZ_NO_REMOTE': '1', 17:00:53 INFO - 'NO_EM_RESTART': '1', 17:00:53 INFO - 'PAGER': '/bin/cat', 17:00:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:53 INFO - 'PWD': '/builds/slave/test', 17:00:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:53 INFO - 'SHELL': '/bin/bash', 17:00:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNmwONNKyK/Listeners', 17:00:53 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 17:00:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:53 INFO - 'USER': 'cltbld', 17:00:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:53 INFO - 'XPC_FLAGS': '0x0', 17:00:53 INFO - 'XPC_SERVICE_NAME': '0', 17:00:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:00:53 INFO - Cleaning up... 17:00:53 INFO - Return code: 0 17:00:53 INFO - Running post-action listener: _resource_record_post_action 17:00:53 INFO - Running post-action listener: _start_resource_monitoring 17:00:54 INFO - Starting resource monitoring. 17:00:54 INFO - [mozharness: 2016-11-03 00:00:54.015223Z] Finished create-virtualenv step (success) 17:00:54 INFO - [mozharness: 2016-11-03 00:00:54.016211Z] Running install step. 17:00:54 INFO - Running pre-action listener: _resource_record_pre_action 17:00:54 INFO - Running main action method: install 17:00:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:00:54 INFO - Reading from file tmpfile_stdout 17:00:54 INFO - Detecting whether we're running mozinstall >=1.0... 17:00:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:00:54 INFO - Reading from file tmpfile_stdout 17:00:54 INFO - Output received: 17:00:54 INFO - Usage: mozinstall [options] installer 17:00:54 INFO - Options: 17:00:54 INFO - -h, --help show this help message and exit 17:00:54 INFO - -d DEST, --destination=DEST 17:00:54 INFO - Directory to install application into. [default: 17:00:54 INFO - "/builds/slave/test"] 17:00:54 INFO - --app=APP Application being installed. [default: firefox] 17:00:54 INFO - mkdir: /builds/slave/test/build/application 17:00:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:01:28 INFO - Reading from file tmpfile_stdout 17:01:28 INFO - Output received: 17:01:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:01:28 INFO - Running post-action listener: _resource_record_post_action 17:01:28 INFO - [mozharness: 2016-11-03 00:01:28.200335Z] Finished install step (success) 17:01:28 INFO - [mozharness: 2016-11-03 00:01:28.200480Z] Running run-tests step. 17:01:28 INFO - Running pre-action listener: _resource_record_pre_action 17:01:28 INFO - Running main action method: run_tests 17:01:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:01:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:01:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:01:28 INFO - 2016-11-02 17:01:28.214 screenresolution[1649:9115] starting screenresolution argv=screenresolution get 17:01:28 INFO - 2016-11-02 17:01:28.217 screenresolution[1649:9115] Display 0: 1600x1200x32@60 17:01:28 INFO - 2016-11-02 17:01:28.223 screenresolution[1650:9117] starting screenresolution argv=screenresolution list 17:01:28 INFO - Available Modes on Display 0 17:01:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:01:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:01:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:01:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:01:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:01:28 INFO - Intel Iris: 17:01:28 INFO - Chipset Model: Intel Iris 17:01:28 INFO - Type: GPU 17:01:28 INFO - Bus: Built-In 17:01:28 INFO - VRAM (Dynamic, Max): 1536 MB 17:01:28 INFO - Vendor: Intel (0x8086) 17:01:28 INFO - Device ID: 0x0a2e 17:01:28 INFO - Revision ID: 0x0009 17:01:28 INFO - Displays: 17:01:28 INFO - Display: 17:01:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:01:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:01:28 INFO - Display Serial Number: 1600x1200 60 17:01:28 INFO - Main Display: Yes 17:01:28 INFO - Mirror: Off 17:01:28 INFO - Online: Yes 17:01:28 INFO - Rotation: Supported 17:01:28 INFO - Return code: 0 17:01:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:01:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:01:28 INFO - Python 2.7.3 17:01:28 INFO - Return code: 0 17:01:28 INFO - grabbing minidump binary from tooltool 17:01:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:01:28 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 0x110344d30>, '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 0x11016adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11033cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:01:28 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 17:01:28 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 17:01:28 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 17:01:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:01:28 INFO - Return code: 0 17:01:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:01:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:01:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:01:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:01:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:01:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--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 17:01:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0228 --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 17:01:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSLj4b7vUe/Render', 17:01:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:01:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:01:28 INFO - 'HOME': '/Users/cltbld', 17:01:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:01:28 INFO - 'LOGNAME': 'cltbld', 17:01:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:01:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:01:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:01:28 INFO - 'MOZ_NO_REMOTE': '1', 17:01:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:01:28 INFO - 'NO_EM_RESTART': '1', 17:01:28 INFO - 'PAGER': '/bin/cat', 17:01:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:01:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:01:28 INFO - 'PWD': '/builds/slave/test', 17:01:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:01:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:01:28 INFO - 'SHELL': '/bin/bash', 17:01:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNmwONNKyK/Listeners', 17:01:28 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 17:01:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:01:28 INFO - 'USER': 'cltbld', 17:01:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:01:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:01:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:01:28 INFO - 'XPC_FLAGS': '0x0', 17:01:28 INFO - 'XPC_SERVICE_NAME': '0', 17:01:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:01:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--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 17:01:28 INFO - mozversion application_buildid: 20161102143123 17:01:28 INFO - mozversion application_changeset: ee7c1fd6966586f0ffab187d164b3e282f771cab 17:01:28 INFO - mozversion application_display_name: Nightly 17:01:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:01:28 INFO - mozversion application_name: Firefox 17:01:28 INFO - mozversion application_remotingname: firefox 17:01:28 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 17:01:28 INFO - mozversion application_vendor: Mozilla 17:01:28 INFO - mozversion application_version: 52.0a1 17:01:28 INFO - mozversion platform_buildid: 20161102143123 17:01:28 INFO - mozversion platform_changeset: ee7c1fd6966586f0ffab187d164b3e282f771cab 17:01:28 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 17:01:28 INFO - mozversion platform_version: 52.0a1 17:01:28 INFO - using testdate: 1478131288 17:01:28 INFO - actual date: 1478131288 17:01:28 INFO - starting webserver on 'localhost:49280' 17:01:28 INFO - SUITE-START | Running 2 tests 17:01:28 INFO - TEST-START | dromaeo_css 17:01:28 INFO - operating with platform_type : mac_ 17:01:28 INFO - Initialising browser for dromaeo_css test... 17:01:28 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmprE59k2/profile http://localhost:49280/getInfo.html) 17:01:31 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 17:01:31 INFO - PROCESS | 1660 | colorDepth:24 17:01:31 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 17:01:31 INFO - PROCESS | 1660 | __metrics 17:01:31 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:01:31 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:01:32 INFO - TEST-INFO | 1660: exit 0 17:01:32 INFO - Browser initialized. 17:01:32 INFO - Running cycle 1/1 for dromaeo_css test... 17:01:32 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmprE59k2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:01:44 INFO - PROCESS | 1663 | RSS: Main: 240517120 17:01:44 INFO - PROCESS | 1663 | 17:02:35 INFO - PROCESS | 1663 | 1478131355887 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] 17:04:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 17:04:50 INFO - PROCESS | 1663 | RSS: Main: 447623168 17:04:50 INFO - PROCESS | 1663 | 17:07:53 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 17:07:54 INFO - PROCESS | 1663 | RSS: Main: 371843072 17:07:54 INFO - PROCESS | 1663 | 17:10:56 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 17:10:57 INFO - PROCESS | 1663 | RSS: Main: 346521600 17:10:57 INFO - PROCESS | 1663 | 17:13:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 17:14:00 INFO - PROCESS | 1663 | RSS: Main: 346963968 17:14:00 INFO - PROCESS | 1663 | 17:17:03 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 17:17:04 INFO - PROCESS | 1663 | RSS: Main: 336105472 17:17:04 INFO - PROCESS | 1663 | 17:20:07 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 17:20:07 INFO - PROCESS | 1663 | RSS: Main: 405188608 17:20:07 INFO - PROCESS | 1663 | 17:20:07 INFO - PROCESS | 1663 | __start_tp_report 17:20:07 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 17:20:07 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 17:20:07 INFO - PROCESS | 1663 | |i|pagename|runs| 17:20:07 INFO - PROCESS | 1663 | |0;dojo.html;2745.6293400390946;3263.102646772136;3264.9347013059732;3274.099622603785;3305.4545999910015;5516.14499752539;5555.30558680165;5555.416681248471;5569.554435645144;5587.636803607767;4986.75066246688;5423.267858839055;5589.608727389084;5600.383957764653;5666.660000399968;5048.974755126219;5075.5178258065425;5084.720340381277;5093.2105523643895;5141.383034035921;5100.84697459075;5191.402988656297;5201.427842937273;5205.843824685247;5251.632385733;5030.245463180516;5173.224016397533;5178.766955487012;5188.610854185932;5257.684538927677;4239.40648309237;5065.569426598707;5089.083964886319;5091.389033315989;5127.794888204505;5075.847724568269;5119.692818430869;5121.28302037715;5162.92255616166;5173.896522069575;4607.401037865089;4639.211334073216;5558.527525160366;5581.107022876361;5609.775608975636;4921.729304888229;5867.647941123546;5910.408959104098;5943.851403714942;6151.231096112986;6141.539384546132;6143.416375444325;6174.166487524217;6227.09707092469;6239.126522286883;5733.88532229353;5736.713164341766;5752.856178595569;5761.366249712528;5804.912926306109;5689.6586204827845;5960.4635582797755;6083.422074902877;6156.969215153896;6164.907526387108;5868.765249390062;5912.408759124053;5923.526117910556;6035.698215089228;6099.786507472217;6155.753769849156;6162.691865406712;6166.136740856284;6183.227096612924;6184.381561843779;6084.239470066242;6117.84705382369;6130.816075517741;6141.907871381933;6143.139960405547;6046.516278697782;6108.053251745898;6125.846853828601;6145.723442445012;6162.815115546541;4835.588974937099;4838.322634831126;4839.2983017462975;4840.104580652591;4841.443234028062;4305.6986010978935;4354.760488173118;4421.182081314967;4432.180046691308;4443.511213766483;5635.436456354414;5697.658140511582;5698.772049118072;5699.772009119672;5718.79984200551;5158.638895277295;6089.157712751514;6105.672036200918;6131.621347939075;6160.322364539897;5750.25246717924;5750.683712395775;5753.568482363839;5767.077267637158;5809.7095145242565;5318.813841515528;5322.813701520428;5329.147336426153;5336.679799211982;5339.225812257201;6152.230971128609;6155.969220153871;6199.659018754011;6204.534659900616;6239.251289845157;5090.770915308746;6180.165677633462;6221.346758590283;6226.813195604139;6233.906491402542;6320.873582528232;6322.3045464997895;6326.2092238470195;6337.112804207322;6346.714397852015;5012.423571289349;5869.7945571904775;5949.881002380015;6111.602745821685;6119.611956212886;6110.7861224856915;6120.479759220516;6127.14220009208;6129.877402452015;6143.84640383994;5798.53064444544;5909.793157239476;5942.465178133989;5955.255593050869;5956.374580668968;4788.377510923697;5871.1486834409625;5905.114232865105;5905.616134951214;5924.6149000314845;5605.747741351567;5778.595498315078;5784.768609255751;5816.680082595329;5818.389069147678;5638.802641907513;5922.5261979040715;5927.555433342603;5944.078667806497;6064.423879731418;5803.82588522345;5805.82582522525;5810.331811841524;5821.476067153976;5867.6772777497645 17:20:07 INFO - PROCESS | 1663 | |1;ext.html;478.86113027222086;495.64808985620186;507.7334399440293;511.6904272914886;525.1020754509788;40699.77900662981;41856.581434185646;41869;43889.78055109728;43913;42334.576654233446;42683.14633707324;42718.35922461165;42741.35887961683;42835.57164428355;8682.43564168327;9472.90527094727;9645.855312170323;9649.469279189641;9650.420974741539;8777.95611021944;11448.828267575993;11693.239939403911;11711.707207319843;11842.58550950717;11533.826992595117;11563.826542601868;11625.360605166754;11636.476358563885;11727.941360293187;8652.610632521488;8882.733517994471;11587;11667.708307292343;11668.474918628683;11214.495347709291;11505.252158609663;11561.537538498449;11712.765744685144;11720.531178752839;40070.59929400698;40239.39640905389;40239.79880100581;40419.59580404188;40587.594124058975;3319.0872510059685;3368.9326213475592;3636.61815509373;3851.171998020411;3997.400389941532;30186.69813301861;30885.69114308873;31536;31591.684083159333;31656.683433165836;3072.139800855764;3161.8419079046184;3186.9043928682177;3213.084270982782;3230.1924518870283;487789.99999999645;490817;493876;497723;504094.95905040845;60469.395306047256;60822;60838.695806520685;60840.39159608436;60917.39082609206;64527.67736161289;64651.38371540749;64683.353166468674;64731.676341618935;64816.35183648103;15422.228888555528;15619.453319133776;15622.76565851513;15623.843761562239;15640.983336083345;10897.291676041032;11134.27627204229;11137.498812553455;12072.396380180955;12154.453049612612;19152.369960288925;19842.50393740168;19917.60164796725;20402.97985100739;20450.693239601416;6731;7987.640556174986;7991.24083212094;8003.319717823933;8106.756797296091;840.8738689196547;843.7679638099572;851.3104385447807;916.4867674102524;986.6201512417771;29985.250368740955;30073.849630751705;30283.394332113676;30369.24076898051;30788;899.5906862377602;1013.8631284776461;1032.5869652139204;1045.5242864496636;1076.5855145768778;13789.24159171235;15191.392344306325;15731.056136631838;15923.283452244677;15930.283137258852;615564;618929.3374731953;619546;620683;621160;58681;59040;59169;59171;59249.70375148096;1156.4969238381332;1292.2052937443593;1346.0981142634262;1347.7506661267696;1348.919064856092;28868.566971495864;29305.97429090057;29362.853185733933;30265.69734302717;30390;51716.24256361425;52133.47866521438;52220.21669674882;52243.73878130737;52264;602524;605105;609026;610402;610494;30791.53812692856;35074.473882892285;35122;35335.45024026405;42487.5751242496;39440.01399965024;47094.76452617852;48527.5147248523;48636.75681621569;48737.7815554614;29357.85321073381;35024.824875875456;37631.81184094062;38883.61116388913;42171.10230039594;38142.61857381391;38207.808960955015;38542.80728596339;39554.802225988686;40186.39720404138;38050.619493804705;38052.42921356126;38703.22593548169;38879.22241555097;39208.607913920496;27341;32769.01692949215;33892.49161262534;34749.957501275;34852.65147348595;24615.12316058844;25044.123455678964;25163.119290824732;25750.613740794277;25872.482550349265 17:20:07 INFO - PROCESS | 1663 | |2;jquery.html;306.2328866190193;353.5368667046168;362.1417241138502;371.90702324418885;387.21588782714997;14595.854041459583;14778;15372.000819946723;15437.305321260543;18716.719249211274;18682.06589670518;18801.717974230363;19195.808041919576;20031.499212519695;20279.59440811183;4627.768611569417;5964.88070238595;5977.163197152402;5977.551683623724;5990.191324171246;5649.548036157097;5807;5826.592138550303;5886.470217680408;5981.0729336952845;5823.767049318022;5826.184334193216;5827.504662103705;5836.387179346171;5842.88314233715;5619.80330688424;5712.428757124296;5738.0532212184935;5812.157237200603;5818.854528636775;5075.873103172414;5081.390233172006;5081.79672813087;5085.516875896784;5099.923501147486;19729.802701973083;23604.7639523606;23760.524789504285;23856.403589910216;24425.022999080193;1892.6782446984046;2223.2885476647316;2235.16181431963;2295.3114065780364;2461.5815311396927;14352.92823535886;14542.418303267857;14672.559823205322;15086.39654413822;15153.154136625546;1676.6311411489453;1799.2263326769485;1833.6607727570442;1851.4815851561584;1895.3745264062964;99871;104632.47683761608;105222;106343;106381.91507443754;10177.949110254549;10307.433091179908;10468.47657617116;10757.462126893624;10898.237123401286;13263.535776247785;13286.468541258435;13292.136011159244;13318.600441986757;13321.06752527333;7745.535267883945;8037.517748935083;8046.839063218703;8153.184681531918;8182.509049457051;6594.505412094016;7777.766666999999;7823.765287041398;7863.33161681252;8007.959960200162;6388.105665206875;6502.60984340941;6504.479641628658;6527.54307198501;6618.53670243088;1826.6529359421668;1858.6654402207462;1859.6094820087662;1861.0087206688984;1863.7390765292869;829.4981536170651;906.5467266366817;940.304174910575;941.7786820097272;1027.291169093323;19502.12240449183;21686.891565542388;21724.782752172276;21772.564548709255;21785.564288714453;792.4690457393444;905.4250550900226;916.5005072235648;940.1444685336447;991.7966816802682;9279.62881484733;10424.322419042737;10878.292910960763;11027.944860275647;11072.889271107348;585897;590058;595559;604135;606297;98115.05654830366;101037.28735609182;112124;132025;135798.32100839433;936.4287784451479;953.6566835939057;997.0926456924165;999.1107913956585;1001.379144930148;18786.342478013205;19149.712754308974;19188;19270.22919083221;19381.90309048446;38342;48121;53103.734481327345;53899.461005391015;55434.61413464696;51967.440976770755;73500.63249683718;76043.23956760361;76993;78536.60731696305;7698.114716807415;7829.29536341732;7972.521648701097;8044.1955804419085;8080.797980050311;9555.61777528891;10095.39427634315;11997.220180688228;12032.639020829389;12352.691182720144;10734.087602553871;10745.516451759533;10774.515146818256;10848;10908.181886358396;10579.047885690325;10601.045905868508;10632.30889992148;10800.891991079989;10944.835827462435;10841.243992533005;11176.832347514957;11185.496652650812;11215.775684486101;11220.66338009861;7126.608036557832;7807.258310460497;7905.723299684483;7931.643076061476;7966;9569.521523923637;10425.270231084054;10433.843492347773;10687.342415514859;10727.302725322828 17:20:07 INFO - PROCESS | 1663 | |3;mootools.html;1285.8842704156625;1469.1992863889182;1592.3710134496873;1774.1572752942361;1825.607494388707;6777.390034896858;8412.411131220817;8413.873791893118;8420.621072051757;8432.451890627104;7501.662425190866;7741;7987.801829725544;8190.2628763411285;8246.381521385905;2274;2305.6426253930667;2305.8847057647135;2308.1113771198125;2312.387217387394;2297.6208925527353;2319.6172631515865;2342.9648555271683;2343.6953196084487;2350.059976009593;1965.8918759468224;2290.942726431836;2291.521968330426;2316.51353215824;2317.2584772872688;2261.5589959957815;2272.659101134843;2295.357299956015;2297.3452566018686;2303.4011157099276;2288.7482376938524;2317.1310758465575;2318.13070098713;2318.594246006959;2322.396176993994;8271.33829293655;8314.625841837133;8378.413511054228;8381.874271885927;8389.161083891622;5028.622853285981;5186.962607478519;5199.818006369759;5202.895942081175;5211.921821172686;3091.2580980564726;3207.695268949446;3210.1493104327374;3551.6093229744706;3556.626554211822;1012.6405126180174;1034.57065317893;1051.9631812886512;1056.4770438632927;1057.1542765787417;9105.681301154427;10742.099633099098;10804.459777011118;10926.99999999992;10968.17738669595;3776.546814382292;4401.339799030171;4601.056783359403;4604.447466304065;4678.274867395559;9363.719088427359;9365.063493650581;9367.250619950524;9375.390599611004;9376.296777741696;5187.144121219956;5188.351456067991;5189.040027594831;5191.402988656278;5192.688438693691;4266.274733295348;4397.780110994438;4603.631709463235;4736.881577960509;4747.430308363018;3717.20080182762;3732.5894151643297;3844.692424606025;3845.576986531479;3887.416887466846;1246.650937737435;1253.9686507837378;1256.8931640810451;1266.316189257809;1273.3505911984955;806.306934837645;839.7438781171679;889.7997950461224;891.3804905590682;945.8271743038582;5370.496261046913;5420.159875219348;5662.745176467069;5711.485966263056;5784.855378615484;949.3307218411142;962.2350231565782;980.6420656460341;984.6406061787396;999.9550020249106;3740.9064773380337;3749.512563366745;3751.962480375216;3865.9226815463535;3894.6105389460827;9630;10009.949950250348;10130.746731331628;10161.441120738282;10162.136218421369;10074.597016119273;10075.496225188712;10082.899171008195;10088.243381746252;10115.988401159972;7717.459777815499;7750.728724494616;7795.376369890395;7870.44906856526;7888.684452621831;5409.675419474844;5435.701036442875;5534.584906131976;5594.692291923983;5611.971940140274;9414.95292523533;9435.433873967584;9442.055794420778;9445.338826282094;9446.29152813528;9176;9386.765330866783;9557.378770379903;9679.758006049906;9680.031996800546;6313.273973492924;6517.120188774455;6526.804195874131;6563.606183628997;6758.560693554903;7802.921970780447;8059.798505037186;8063.27430531255;8105.513669179868;8121.593920303843;6930.801352157367;7094.893576596252;7121.964390178223;7173.713051477883;7192.029076074663;6719.496037797087;6757.898631520629;6984.615846128511;7042.366187043191;7052.118485189351;6134.355892631388;6300.59046161998;6397.8720425592155;6399.424051835357;6418.839529011812;3853.5183102112237;4841.927371089507;4893.388326459192;4894.779734911866;4942.8517144484285;4770.332153498443;4778.402699662543;4897.8285759998225;4931.852044438672;5050.116229659869 17:20:07 INFO - PROCESS | 1663 | |4;prototype.html;252.8520815323036;293.9978744748266;305.18819938962355;310.3931813304989;312.16651540387164;4304.923769057736;4516.035524546816;5120.641555091145;5149.845504634857;5233.2150177473395;4336.631386332166;4744;4776.37907072081;4947.180107338819;5146.944876300358;3766.886993390203;3811.5235595550557;3827.2536855313224;3857.32496813058;3878.45701601776;3867.535895692507;3910.9413358799643;3921.8235179416993;3924.1955399143108;3947.3486874665646;3305.752068594853;3792.58281589025;3854.807259637021;3863.43980122882;3900.609939006105;3545.96107238339;3867.323218436762;3880.670143037817;3901.356276214421;3971.62269584389;2935.722960512184;3252.317013427183;3337.1990722226733;3340.5657264555693;3347.2301370684877;4940.184095065814;4972.328735620681;4974.726390048546;4977.352944117241;5028.429814831474;4134.4831896013;4431.379606855043;4442.888927776799;4445.799939002753;4454.37638730578;4537.978954735158;5342.412334643186;5346.946530534684;5417.485338892799;5418.512333889968;3257.625373082102;3391.9491207631904;3518.736094792901;3712;3758.361078616642;5362;5375.328083989462;5415.83752487426;5608.4111168327145;5608.579356548275;3727.142757165867;3737.4580685800406;3747.887563373103;3749.4375843623675;3752.2683076799763;3816.751911125768;3822.7515211511163;3828.693704503633;3831.8084095795098;3842.4438102568506;4303.311470164758;4438.669698408361;4451.287793952952;4455.732656040648;4473.955260447419;5140.34561981817;5406.945930540723;5419.593530485229;5449.1826226066405;5487.341519017744;1144.9084073274119;1152.2970987697497;1285.8328417305877;1289.7484990426965;1299.876511731384;717.1967396515936;834.3158609939912;839.6230182500703;851.9105493923173;875.5228400521731;4463.776811159429;4641.094986477672;5022.673526220784;5047.545720885065;5057.31726216956;5018.68766614033;5445.945540544623;5463.672179669153;5468.343798744097;5516.972415137899;3978.303796835542;4261.397690494258;4833.806647734122;4849.80600775965;5070.923936140961;2860.5709143628624;2860.599516067752;2862.9284267893468;2871.6410448693914;2882.466743652431;5072.746362681851;5318.760655770499;5454.527277635096;5588.01625422969;5624;4661.440627124767;4702.542211914318;4900.901981960341;5513.889722205534;5618.887622247532;5590.608657393944;5597.804076857372;5619.185218143346;5654.717264136859;5677.091665333444;4941.876453088702;4953.2570114483115;5366.785328586813;5396.946030539644;5457.863553411196;5328.8401347959625;5348.705821179872;5349.491798279158;5356.6785992838995;5373.946260537345;4677.929831052605;5035.525487413673;5333.146696528537;5580.86047848807;5639.577031722555;3285.5071739239306;3513.771604845677;3519.753617246768;3663.2782592181634;3678.889633311005;4867.95132048675;5322.54758345545;5401.243825864303;5434.592405569519;5543.61194716366;4061.817218225246;4911.729854857874;5087.465816089405;5284.656497327662;5288.8148914789335;4464.888377790581;5155.948440515697;5299.973500132474;5300.867478313073;5326.840194794162;4583.633309335179;5027.622928280321;5092.860998380615;5132.2798916574275;5173.430922598586;4408.933865992077;4540.455145382575;5203.479652034766;5232.979568984011;5315.681059136464;4753.762311884461;5251.52736253739;5264.552513036435;5276.736163191902;5292.623917781481 17:20:07 INFO - PROCESS | 1663 | |5;yui.html;681.247221819889;727.7052793618585;779.8908152858601;794.3724457678435;808.6563210635478;16175.919120404396;16714.916425417858;16718.582035449104;16726.49820505384;16808.495745127664;15415.768763468557;15525;15553.611159721013;15586.376544938217;15600.141992190425;633.8668879535303;835.3150416658327;857.8284343131368;859.660434128522;859.7635650196215;693.0609024771438;827.5159031966277;845.4208866926175;851.5912362066211;856.755824589992;792.3423558446505;903.9276857851356;905.5653286422521;906.6871929184421;907.6505545365048;897.2776914583758;899.0964081098487;900.0909081827368;901.5627420700955;902.9638814447414;525.5349016120722;539.4551502981977;642.983925401864;660.9919872194893;662.867426514699;14681.192534410604;14712.485063022743;14722.63193420173;14732.631684207874;14736.410543578244;12556.99544036484;12658.746825063447;12915.806262906064;12923;13122.14706044104;11792.764144717143;12035.09736769737;12075.516979320817;12084.395780211042;12682.858542731106;4337.522872484024;4345.73925564464;4455.576720211575;4540.4551453825425;4558.817647294104;91408.08591914129;91542.5422872888;92897.60653590201;93787;94038;8906.332025098143;10143.442110683984;10228.437435940947;10549.947250263853;10696.251262411712;19321.323753668836;19406.611867762567;19408.61182776365;19427.902860485607;19433;7330.486865919334;7362.190159082496;7403;7519.924800752032;7624.504407213513;4132.383063776143;4230.936535951963;4317.676174286941;4332.523422423531;4337.934930976038;3411.556497655289;3507.3686736387695;3515.876944306937;3518.3491054155065;3522.5420695309444;637.5441559285133;639.0861068671751;645.1612903225788;646.7930262316107;651.9641419721866;542.6255883440492;598.8293336399105;602.217117746936;614.9729950981871;714.731975509184;8581.785455363666;8691.478511289342;9717.46553939526;9861.211103111735;9866.802663946824;592.8577141486012;650.8535579494576;672.3444641474621;674.652553934724;688.3770187979886;3530.593981692087;4074.5925407459017;4075.572064933199;4529.501754806968;4660.557247061523;71042.9343559847;72278;103067;107311.0495635578;112608.43695781469;74999.87500187501;80409;97009.51495242478;97456.51271743597;99541;56196.43803561994;68074;73600;79723.20276797273;87996.5600171995;9464.337496375188;9652.9034709652;9655.951720241354;9677.661281855102;9679.612815487302;52898;53273.742569901726;54085.459145409615;54761;56005.719971399885;69109.30890691029;72782.23196056057;77155.61422192852;77853.61073194824;78504.21495784969;11880.584179553674;12085.395730213279;12106.455209515418;12205.450754716237;12441.253524788543;13962.58112256634;13963.720725585634;14282.785758213842;14394.568162955127;14433.711325773636;12795.552155674506;13114.267186115303;13133.868661313265;13147.342632868127;13215;10453.634122805664;13090.083694141318;13123.475060997453;13315.53395631187;13758.793618095937;12765.36173191318;12779.36103194818;12872;13035.934820325838;13063.216207027608;10286.125679317342;10875.293105948089;10945.890541094486;11324.980751732384;11347.37589432589;10946.945265273622;11017.614383496539;11027.779444410906;11139.220254582426;11203.831942521032 17:20:07 INFO - PROCESS | 1663 | __end_tp_report 17:20:07 INFO - PROCESS | 1663 | __start_cc_report 17:20:07 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,3084 17:20:07 INFO - PROCESS | 1663 | __end_cc_report 17:20:07 INFO - PROCESS | 1663 | __startTimestamp1478132407628__endTimestamp 17:20:07 INFO - PROCESS | 1663 | 17:20:07 INFO - PROCESS | 1663 | ------- Summary: start ------- 17:20:07 INFO - PROCESS | 1663 | Number of tests: 6 17:20:07 INFO - PROCESS | 1663 | 17:20:07 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5589.10 Median:5784.77 stddev:659.47 (11.4%) stddev-sans-first:622.63 17:20:07 INFO - PROCESS | 1663 | Values: 2745.6 3263.1 3264.9 3274.1 3305.5 5516.1 5555.3 5555.4 5569.6 5587.6 4986.8 5423.3 5589.6 5600.4 5666.7 5049.0 5075.5 5084.7 5093.2 5141.4 5100.8 5191.4 5201.4 5205.8 5251.6 5030.2 5173.2 5178.8 5188.6 5257.7 4239.4 5065.6 5089.1 5091.4 5127.8 5075.8 5119.7 5121.3 5162.9 5173.9 4607.4 4639.2 5558.5 5581.1 5609.8 4921.7 5867.6 5910.4 5943.9 6151.2 6141.5 6143.4 6174.2 6227.1 6239.1 5733.9 5736.7 5752.9 5761.4 5804.9 5689.7 5960.5 6083.4 6157.0 6164.9 5868.8 5912.4 5923.5 6035.7 6099.8 6155.8 6162.7 6166.1 6183.2 6184.4 6084.2 6117.8 6130.8 6141.9 6143.1 6046.5 6108.1 6125.8 6145.7 6162.8 4835.6 4838.3 4839.3 4840.1 4841.4 4305.7 4354.8 4421.2 4432.2 4443.5 5635.4 5697.7 5698.8 5699.8 5718.8 5158.6 6089.2 6105.7 6131.6 6160.3 5750.3 5750.7 5753.6 5767.1 5809.7 5318.8 5322.8 5329.1 5336.7 5339.2 6152.2 6156.0 6199.7 6204.5 6239.3 5090.8 6180.2 6221.3 6226.8 6233.9 6320.9 6322.3 6326.2 6337.1 6346.7 5012.4 5869.8 5949.9 6111.6 6119.6 6110.8 6120.5 6127.1 6129.9 6143.8 5798.5 5909.8 5942.5 5955.3 5956.4 4788.4 5871.1 5905.1 5905.6 5924.6 5605.7 5778.6 5784.8 5816.7 5818.4 5638.8 5922.5 5927.6 5944.1 6064.4 5803.8 5805.8 5810.3 5821.5 5867.7 17:20:07 INFO - PROCESS | 1663 | 17:20:07 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:71218.51 Median:21196.99 stddev:153917.56 (726.1%) stddev-sans-first:154257.71 17:20:07 INFO - PROCESS | 1663 | Values: 478.9 495.6 507.7 511.7 525.1 40699.8 41856.6 41869.0 43889.8 43913.0 42334.6 42683.1 42718.4 42741.4 42835.6 8682.4 9472.9 9645.9 9649.5 9650.4 8778.0 11448.8 11693.2 11711.7 11842.6 11533.8 11563.8 11625.4 11636.5 11727.9 8652.6 8882.7 11587.0 11667.7 11668.5 11214.5 11505.3 11561.5 11712.8 11720.5 40070.6 40239.4 40239.8 40419.6 40587.6 3319.1 3368.9 3636.6 3851.2 3997.4 30186.7 30885.7 31536.0 31591.7 31656.7 3072.1 3161.8 3186.9 3213.1 3230.2 487790.0 490817.0 493876.0 497723.0 504095.0 60469.4 60822.0 60838.7 60840.4 60917.4 64527.7 64651.4 64683.4 64731.7 64816.4 15422.2 15619.5 15622.8 15623.8 15641.0 10897.3 11134.3 11137.5 12072.4 12154.5 19152.4 19842.5 19917.6 20403.0 20450.7 6731.0 7987.6 7991.2 8003.3 8106.8 840.9 843.8 851.3 916.5 986.6 29985.3 30073.8 30283.4 30369.2 30788.0 899.6 1013.9 1032.6 1045.5 1076.6 13789.2 15191.4 15731.1 15923.3 15930.3 615564.0 618929.3 619546.0 620683.0 621160.0 58681.0 59040.0 59169.0 59171.0 59249.7 1156.5 1292.2 1346.1 1347.8 1348.9 28868.6 29306.0 29362.9 30265.7 30390.0 51716.2 52133.5 52220.2 52243.7 52264.0 602524.0 605105.0 609026.0 610402.0 610494.0 30791.5 35074.5 35122.0 35335.5 42487.6 39440.0 47094.8 48527.5 48636.8 48737.8 29357.9 35024.8 37631.8 38883.6 42171.1 38142.6 38207.8 38542.8 39554.8 40186.4 38050.6 38052.4 38703.2 38879.2 39208.6 27341.0 32769.0 33892.5 34750.0 34852.7 24615.1 25044.1 25163.1 25750.6 25872.5 17:20:07 INFO - PROCESS | 1663 | 17:20:07 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:33750.12 Median:13443.30 stddev:98972.63 (736.2%) stddev-sans-first:99218.42 17:20:07 INFO - PROCESS | 1663 | Values: 306.2 353.5 362.1 371.9 387.2 14595.9 14778.0 15372.0 15437.3 18716.7 18682.1 18801.7 19195.8 20031.5 20279.6 4627.8 5964.9 5977.2 5977.6 5990.2 5649.5 5807.0 5826.6 5886.5 5981.1 5823.8 5826.2 5827.5 5836.4 5842.9 5619.8 5712.4 5738.1 5812.2 5818.9 5075.9 5081.4 5081.8 5085.5 5099.9 19729.8 23604.8 23760.5 23856.4 24425.0 1892.7 2223.3 2235.2 2295.3 2461.6 14352.9 14542.4 14672.6 15086.4 15153.2 1676.6 1799.2 1833.7 1851.5 1895.4 99871.0 104632.5 105222.0 106343.0 106381.9 10177.9 10307.4 10468.5 10757.5 10898.2 13263.5 13286.5 13292.1 13318.6 13321.1 7745.5 8037.5 8046.8 8153.2 8182.5 6594.5 7777.8 7823.8 7863.3 8008.0 6388.1 6502.6 6504.5 6527.5 6618.5 1826.7 1858.7 1859.6 1861.0 1863.7 829.5 906.5 940.3 941.8 1027.3 19502.1 21686.9 21724.8 21772.6 21785.6 792.5 905.4 916.5 940.1 991.8 9279.6 10424.3 10878.3 11027.9 11072.9 585897.0 590058.0 595559.0 604135.0 606297.0 98115.1 101037.3 112124.0 132025.0 135798.3 936.4 953.7 997.1 999.1 1001.4 18786.3 19149.7 19188.0 19270.2 19381.9 38342.0 48121.0 53103.7 53899.5 55434.6 51967.4 73500.6 76043.2 76993.0 78536.6 7698.1 7829.3 7972.5 8044.2 8080.8 9555.6 10095.4 11997.2 12032.6 12352.7 10734.1 10745.5 10774.5 10848.0 10908.2 10579.0 10601.0 10632.3 10800.9 10944.8 10841.2 11176.8 11185.5 11215.8 11220.7 7126.6 7807.3 7905.7 7931.6 7966.0 9569.5 10425.3 10433.8 10687.3 10727.3 17:20:07 INFO - PROCESS | 1663 | 17:20:07 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5392.79 Median:5187.75 stddev:2958.15 (57.0%) stddev-sans-first:2950.34 17:20:07 INFO - PROCESS | 1663 | Values: 1285.9 1469.2 1592.4 1774.2 1825.6 6777.4 8412.4 8413.9 8420.6 8432.5 7501.7 7741.0 7987.8 8190.3 8246.4 2274.0 2305.6 2305.9 2308.1 2312.4 2297.6 2319.6 2343.0 2343.7 2350.1 1965.9 2290.9 2291.5 2316.5 2317.3 2261.6 2272.7 2295.4 2297.3 2303.4 2288.7 2317.1 2318.1 2318.6 2322.4 8271.3 8314.6 8378.4 8381.9 8389.2 5028.6 5187.0 5199.8 5202.9 5211.9 3091.3 3207.7 3210.1 3551.6 3556.6 1012.6 1034.6 1052.0 1056.5 1057.2 9105.7 10742.1 10804.5 10927.0 10968.2 3776.5 4401.3 4601.1 4604.4 4678.3 9363.7 9365.1 9367.3 9375.4 9376.3 5187.1 5188.4 5189.0 5191.4 5192.7 4266.3 4397.8 4603.6 4736.9 4747.4 3717.2 3732.6 3844.7 3845.6 3887.4 1246.7 1254.0 1256.9 1266.3 1273.4 806.3 839.7 889.8 891.4 945.8 5370.5 5420.2 5662.7 5711.5 5784.9 949.3 962.2 980.6 984.6 1000.0 3740.9 3749.5 3752.0 3865.9 3894.6 9630.0 10009.9 10130.7 10161.4 10162.1 10074.6 10075.5 10082.9 10088.2 10116.0 7717.5 7750.7 7795.4 7870.4 7888.7 5409.7 5435.7 5534.6 5594.7 5612.0 9415.0 9435.4 9442.1 9445.3 9446.3 9176.0 9386.8 9557.4 9679.8 9680.0 6313.3 6517.1 6526.8 6563.6 6758.6 7802.9 8059.8 8063.3 8105.5 8121.6 6930.8 7094.9 7122.0 7173.7 7192.0 6719.5 6757.9 6984.6 7042.4 7052.1 6134.4 6300.6 6397.9 6399.4 6418.8 3853.5 4841.9 4893.4 4894.8 4942.9 4770.3 4778.4 4897.8 4931.9 5050.1 17:20:07 INFO - PROCESS | 1663 | 17:20:07 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4309.94 Median:4858.88 stddev:1308.44 (26.9%) stddev-sans-first:1276.18 17:20:07 INFO - PROCESS | 1663 | Values: 252.9 294.0 305.2 310.4 312.2 4304.9 4516.0 5120.6 5149.8 5233.2 4336.6 4744.0 4776.4 4947.2 5146.9 3766.9 3811.5 3827.3 3857.3 3878.5 3867.5 3910.9 3921.8 3924.2 3947.3 3305.8 3792.6 3854.8 3863.4 3900.6 3546.0 3867.3 3880.7 3901.4 3971.6 2935.7 3252.3 3337.2 3340.6 3347.2 4940.2 4972.3 4974.7 4977.4 5028.4 4134.5 4431.4 4442.9 4445.8 4454.4 4538.0 5342.4 5346.9 5417.5 5418.5 3257.6 3391.9 3518.7 3712.0 3758.4 5362.0 5375.3 5415.8 5608.4 5608.6 3727.1 3737.5 3747.9 3749.4 3752.3 3816.8 3822.8 3828.7 3831.8 3842.4 4303.3 4438.7 4451.3 4455.7 4474.0 5140.3 5406.9 5419.6 5449.2 5487.3 1144.9 1152.3 1285.8 1289.7 1299.9 717.2 834.3 839.6 851.9 875.5 4463.8 4641.1 5022.7 5047.5 5057.3 5018.7 5445.9 5463.7 5468.3 5517.0 3978.3 4261.4 4833.8 4849.8 5070.9 2860.6 2860.6 2862.9 2871.6 2882.5 5072.7 5318.8 5454.5 5588.0 5624.0 4661.4 4702.5 4900.9 5513.9 5618.9 5590.6 5597.8 5619.2 5654.7 5677.1 4941.9 4953.3 5366.8 5396.9 5457.9 5328.8 5348.7 5349.5 5356.7 5373.9 4677.9 5035.5 5333.1 5580.9 5639.6 3285.5 3513.8 3519.8 3663.3 3678.9 4868.0 5322.5 5401.2 5434.6 5543.6 4061.8 4911.7 5087.5 5284.7 5288.8 4464.9 5155.9 5300.0 5300.9 5326.8 4583.6 5027.6 5092.9 5132.3 5173.4 4408.9 4540.5 5203.5 5233.0 5315.7 4753.8 5251.5 5264.6 5276.7 5292.6 17:20:07 INFO - PROCESS | 1663 | 17:20:07 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:19895.50 Median:4609.69 stddev:28229.68 (612.4%) stddev-sans-first:28272.01 17:20:07 INFO - PROCESS | 1663 | Values: 681.2 727.7 779.9 794.4 808.7 16175.9 16714.9 16718.6 16726.5 16808.5 15415.8 15525.0 15553.6 15586.4 15600.1 633.9 835.3 857.8 859.7 859.8 693.1 827.5 845.4 851.6 856.8 792.3 903.9 905.6 906.7 907.7 897.3 899.1 900.1 901.6 903.0 525.5 539.5 643.0 661.0 662.9 14681.2 14712.5 14722.6 14732.6 14736.4 12557.0 12658.7 12915.8 12923.0 13122.1 11792.8 12035.1 12075.5 12084.4 12682.9 4337.5 4345.7 4455.6 4540.5 4558.8 91408.1 91542.5 92897.6 93787.0 94038.0 8906.3 10143.4 10228.4 10549.9 10696.3 19321.3 19406.6 19408.6 19427.9 19433.0 7330.5 7362.2 7403.0 7519.9 7624.5 4132.4 4230.9 4317.7 4332.5 4337.9 3411.6 3507.4 3515.9 3518.3 3522.5 637.5 639.1 645.2 646.8 652.0 542.6 598.8 602.2 615.0 714.7 8581.8 8691.5 9717.5 9861.2 9866.8 592.9 650.9 672.3 674.7 688.4 3530.6 4074.6 4075.6 4529.5 4660.6 71042.9 72278.0 103067.0 107311.0 112608.4 74999.9 80409.0 97009.5 97456.5 99541.0 56196.4 68074.0 73600.0 79723.2 87996.6 9464.3 9652.9 9656.0 9677.7 9679.6 52898.0 53273.7 54085.5 54761.0 56005.7 69109.3 72782.2 77155.6 77853.6 78504.2 11880.6 12085.4 12106.5 12205.5 12441.3 13962.6 13963.7 14282.8 14394.6 14433.7 12795.6 13114.3 13133.9 13147.3 13215.0 10453.6 13090.1 13123.5 13315.5 13758.8 12765.4 12779.4 12872.0 13035.9 13063.2 10286.1 10875.3 10945.9 11325.0 11347.4 10946.9 11017.6 11027.8 11139.2 11203.8 17:20:07 INFO - PROCESS | 1663 | -------- Summary: end -------- 17:20:07 INFO - PROCESS | 1663 | 17:20:08 INFO - TEST-INFO | 1663: exit 0 17:20:08 INFO - TEST-OK | dromaeo_css | took 1119530ms 17:20:08 INFO - TEST-START | kraken 17:20:08 INFO - operating with platform_type : mac_ 17:20:08 INFO - Initialising browser for kraken test... 17:20:08 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpKwyMUN/profile http://localhost:49280/getInfo.html) 17:20:10 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 17:20:10 INFO - PROCESS | 1668 | colorDepth:24 17:20:10 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 17:20:10 INFO - PROCESS | 1668 | __metrics 17:20:10 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:20:10 INFO - PROCESS | 1668 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:20:11 INFO - TEST-INFO | 1668: exit 0 17:20:11 INFO - Browser initialized. 17:20:11 INFO - Running cycle 1/1 for kraken test... 17:20:11 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpKwyMUN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:20:23 INFO - PROCESS | 1669 | RSS: Main: 239788032 17:20:23 INFO - PROCESS | 1669 | 17:20:26 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 17:20:27 INFO - PROCESS | 1669 | RSS: Main: 290668544 17:20:27 INFO - PROCESS | 1669 | 17:20:30 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 17:20:30 INFO - PROCESS | 1669 | RSS: Main: 289701888 17:20:30 INFO - PROCESS | 1669 | 17:20:34 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 17:20:34 INFO - PROCESS | 1669 | RSS: Main: 382660608 17:20:34 INFO - PROCESS | 1669 | 17:20:36 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 17:20:37 INFO - PROCESS | 1669 | RSS: Main: 284569600 17:20:37 INFO - PROCESS | 1669 | 17:20:39 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:20:39 INFO - PROCESS | 1669 | RSS: Main: 289505280 17:20:39 INFO - PROCESS | 1669 | 17:20:42 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 17:20:43 INFO - PROCESS | 1669 | RSS: Main: 326606848 17:20:43 INFO - PROCESS | 1669 | 17:20:46 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 17:20:46 INFO - PROCESS | 1669 | RSS: Main: 327315456 17:20:46 INFO - PROCESS | 1669 | 17:20:49 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 17:20:50 INFO - PROCESS | 1669 | RSS: Main: 326885376 17:20:50 INFO - PROCESS | 1669 | 17:20:51 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:20:52 INFO - PROCESS | 1669 | RSS: Main: 303452160 17:20:52 INFO - PROCESS | 1669 | 17:20:54 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:20:55 INFO - PROCESS | 1669 | RSS: Main: 302424064 17:20:55 INFO - PROCESS | 1669 | 17:20:57 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:20:58 INFO - PROCESS | 1669 | RSS: Main: 306089984 17:20:58 INFO - PROCESS | 1669 | 17:21:01 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:21:01 INFO - PROCESS | 1669 | RSS: Main: 326574080 17:21:01 INFO - PROCESS | 1669 | 17:21:04 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:21:05 INFO - PROCESS | 1669 | RSS: Main: 319848448 17:21:05 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 17:21:07 INFO - PROCESS | 1669 | RSS: Main: 363118592 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | __start_tp_report 17:21:07 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 17:21:07 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 17:21:07 INFO - PROCESS | 1669 | |i|pagename|runs| 17:21:07 INFO - PROCESS | 1669 | |0;ai-astar;112;113;113;112;111;112;113;112;112;114 17:21:07 INFO - PROCESS | 1669 | |1;audio-beat-detection;149;114;137;170;112;106;113;114;153;138 17:21:07 INFO - PROCESS | 1669 | |2;audio-dft;183;181;180;180;185;185;181;178;182;182 17:21:07 INFO - PROCESS | 1669 | |3;audio-fft;83;59;58;90;62;59;59;57;80;57 17:21:07 INFO - PROCESS | 1669 | |4;audio-oscillator;84;71;91;71;68;69;92;71;71;94 17:21:07 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;88;84;87;85;87;84;88;84;87;83 17:21:07 INFO - PROCESS | 1669 | |6;imaging-darkroom;93;94;93;94;94;93;94;97;93;93 17:21:07 INFO - PROCESS | 1669 | |7;imaging-desaturate;85;82;79;89;131;122;83;81;80;80 17:21:07 INFO - PROCESS | 1669 | |8;json-parse-financial;55;54;54;54;53;55;55;56;55;55 17:21:07 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;65;80;95;115;88;69;69;71;68;94 17:21:07 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;95;107;107;98;86;87;84;89;87;88 17:21:07 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;150;149;162;161;157;151;141;152;214;151 17:21:07 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;173;174;239;198;171;233;207;172;172;175 17:21:07 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;68;68;69;67;65;67;67;65;66;64 17:21:07 INFO - PROCESS | 1669 | __end_tp_report 17:21:07 INFO - PROCESS | 1669 | __start_cc_report 17:21:07 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,2039 17:21:07 INFO - PROCESS | 1669 | __end_cc_report 17:21:07 INFO - PROCESS | 1669 | __startTimestamp1478132467541__endTimestamp 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | ------- Summary: start ------- 17:21:07 INFO - PROCESS | 1669 | Number of tests: 14 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:112.40 Median:112.50 stddev:0.84 (0.7%) stddev-sans-first:0.88 17:21:07 INFO - PROCESS | 1669 | Values: 112.0 113.0 113.0 112.0 111.0 112.0 113.0 112.0 112.0 114.0 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:130.60 Median:137.50 stddev:21.86 (15.9%) stddev-sans-first:22.15 17:21:07 INFO - PROCESS | 1669 | Values: 149.0 114.0 137.0 170.0 112.0 106.0 113.0 114.0 153.0 138.0 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:181.70 Median:182.00 stddev:2.21 (1.2%) stddev-sans-first:2.30 17:21:07 INFO - PROCESS | 1669 | Values: 183.0 181.0 180.0 180.0 185.0 185.0 181.0 178.0 182.0 182.0 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:66.40 Median:60.50 stddev:12.69 (21.0%) stddev-sans-first:11.95 17:21:07 INFO - PROCESS | 1669 | Values: 83.0 59.0 58.0 90.0 62.0 59.0 59.0 57.0 80.0 57.0 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:77.50 stddev:10.72 (13.8%) stddev-sans-first:11.16 17:21:07 INFO - PROCESS | 1669 | Values: 84.0 71.0 91.0 71.0 68.0 69.0 92.0 71.0 71.0 94.0 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:87.00 stddev:1.89 (2.2%) stddev-sans-first:1.81 17:21:07 INFO - PROCESS | 1669 | Values: 88.0 84.0 87.0 85.0 87.0 84.0 88.0 84.0 87.0 83.0 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:1.23 (1.3%) stddev-sans-first:1.27 17:21:07 INFO - PROCESS | 1669 | Values: 93.0 94.0 93.0 94.0 94.0 93.0 94.0 97.0 93.0 93.0 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:91.20 Median:81.50 stddev:18.95 (23.3%) stddev-sans-first:19.97 17:21:07 INFO - PROCESS | 1669 | Values: 85.0 82.0 79.0 89.0 131.0 122.0 83.0 81.0 80.0 80.0 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:0.84 (1.5%) stddev-sans-first:0.88 17:21:07 INFO - PROCESS | 1669 | Values: 55.0 54.0 54.0 54.0 53.0 55.0 55.0 56.0 55.0 55.0 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:81.40 Median:75.50 stddev:16.27 (21.5%) stddev-sans-first:16.14 17:21:07 INFO - PROCESS | 1669 | Values: 65.0 80.0 95.0 115.0 88.0 69.0 69.0 71.0 68.0 94.0 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:92.80 Median:87.50 stddev:8.59 (9.8%) stddev-sans-first:9.07 17:21:07 INFO - PROCESS | 1669 | Values: 95.0 107.0 107.0 98.0 86.0 87.0 84.0 89.0 87.0 88.0 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:158.80 Median:154.50 stddev:20.34 (13.2%) stddev-sans-first:21.32 17:21:07 INFO - PROCESS | 1669 | Values: 150.0 149.0 162.0 161.0 157.0 151.0 141.0 152.0 214.0 151.0 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.40 Median:186.50 stddev:26.59 (14.3%) stddev-sans-first:27.35 17:21:07 INFO - PROCESS | 1669 | Values: 173.0 174.0 239.0 198.0 171.0 233.0 207.0 172.0 172.0 175.0 17:21:07 INFO - PROCESS | 1669 | 17:21:07 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.58 (2.4%) stddev-sans-first:1.59 17:21:07 INFO - PROCESS | 1669 | Values: 68.0 68.0 69.0 67.0 65.0 67.0 67.0 65.0 66.0 64.0 17:21:07 INFO - PROCESS | 1669 | -------- Summary: end -------- 17:21:07 INFO - PROCESS | 1669 | 17:21:08 INFO - TEST-INFO | 1669: exit 0 17:21:08 INFO - TEST-OK | kraken | took 60198ms 17:21:08 INFO - SUITE-END | took 1179s 17:21:08 INFO - Completed test suite (00:19:39) 17:21:08 INFO - javascript benchmark 17:21:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2745.6293400390946, 3263.102646772136, 3264.9347013059732, 3274.099622603785, 3305.4545999910015, 5516.14499752539, 5555.30558680165, 5555.416681248471, 5569.554435645144, 5587.636803607767, 4986.75066246688, 5423.267858839055, 5589.608727389084, 5600.383957764653, 5666.660000399968, 5048.974755126219, 5075.5178258065425, 5084.720340381277, 5093.2105523643895, 5141.383034035921, 5100.84697459075, 5191.402988656297, 5201.427842937273, 5205.843824685247, 5251.632385733, 5030.245463180516, 5173.224016397533, 5178.766955487012, 5188.610854185932, 5257.684538927677, 4239.40648309237, 5065.569426598707, 5089.083964886319, 5091.389033315989, 5127.794888204505, 5075.847724568269, 5119.692818430869, 5121.28302037715, 5162.92255616166, 5173.896522069575, 4607.401037865089, 4639.211334073216, 5558.527525160366, 5581.107022876361, 5609.775608975636, 4921.729304888229, 5867.647941123546, 5910.408959104098, 5943.851403714942, 6151.231096112986, 6141.539384546132, 6143.416375444325, 6174.166487524217, 6227.09707092469, 6239.126522286883, 5733.88532229353, 5736.713164341766, 5752.856178595569, 5761.366249712528, 5804.912926306109, 5689.6586204827845, 5960.4635582797755, 6083.422074902877, 6156.969215153896, 6164.907526387108, 5868.765249390062, 5912.408759124053, 5923.526117910556, 6035.698215089228, 6099.786507472217, 6155.753769849156, 6162.691865406712, 6166.136740856284, 6183.227096612924, 6184.381561843779, 6084.239470066242, 6117.84705382369, 6130.816075517741, 6141.907871381933, 6143.139960405547, 6046.516278697782, 6108.053251745898, 6125.846853828601, 6145.723442445012, 6162.815115546541, 4835.588974937099, 4838.322634831126, 4839.2983017462975, 4840.104580652591, 4841.443234028062, 4305.6986010978935, 4354.760488173118, 4421.182081314967, 4432.180046691308, 4443.511213766483, 5635.436456354414, 5697.658140511582, 5698.772049118072, 5699.772009119672, 5718.79984200551, 5158.638895277295, 6089.157712751514, 6105.672036200918, 6131.621347939075, 6160.322364539897, 5750.25246717924, 5750.683712395775, 5753.568482363839, 5767.077267637158, 5809.7095145242565, 5318.813841515528, 5322.813701520428, 5329.147336426153, 5336.679799211982, 5339.225812257201, 6152.230971128609, 6155.969220153871, 6199.659018754011, 6204.534659900616, 6239.251289845157, 5090.770915308746, 6180.165677633462, 6221.346758590283, 6226.813195604139, 6233.906491402542, 6320.873582528232, 6322.3045464997895, 6326.2092238470195, 6337.112804207322, 6346.714397852015, 5012.423571289349, 5869.7945571904775, 5949.881002380015, 6111.602745821685, 6119.611956212886, 6110.7861224856915, 6120.479759220516, 6127.14220009208, 6129.877402452015, 6143.84640383994, 5798.53064444544, 5909.793157239476, 5942.465178133989, 5955.255593050869, 5956.374580668968, 4788.377510923697, 5871.1486834409625, 5905.114232865105, 5905.616134951214, 5924.6149000314845, 5605.747741351567, 5778.595498315078, 5784.768609255751, 5816.680082595329, 5818.389069147678, 5638.802641907513, 5922.5261979040715, 5927.555433342603, 5944.078667806497, 6064.423879731418, 5803.82588522345, 5805.82582522525, 5810.331811841524, 5821.476067153976, 5867.6772777497645], "value": 5547.111806963016, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.86113027222086, 495.64808985620186, 507.7334399440293, 511.6904272914886, 525.1020754509788, 40699.77900662981, 41856.581434185646, 41869.0, 43889.78055109728, 43913.0, 42334.576654233446, 42683.14633707324, 42718.35922461165, 42741.35887961683, 42835.57164428355, 8682.43564168327, 9472.90527094727, 9645.855312170323, 9649.469279189641, 9650.420974741539, 8777.95611021944, 11448.828267575993, 11693.239939403911, 11711.707207319843, 11842.58550950717, 11533.826992595117, 11563.826542601868, 11625.360605166754, 11636.476358563885, 11727.941360293187, 8652.610632521488, 8882.733517994471, 11587.0, 11667.708307292343, 11668.474918628683, 11214.495347709291, 11505.252158609663, 11561.537538498449, 11712.765744685144, 11720.531178752839, 40070.59929400698, 40239.39640905389, 40239.79880100581, 40419.59580404188, 40587.594124058975, 3319.0872510059685, 3368.9326213475592, 3636.61815509373, 3851.171998020411, 3997.400389941532, 30186.69813301861, 30885.69114308873, 31536.0, 31591.684083159333, 31656.683433165836, 3072.139800855764, 3161.8419079046184, 3186.9043928682177, 3213.084270982782, 3230.1924518870283, 487789.99999999645, 490817.0, 493876.0, 497723.0, 504094.95905040845, 60469.395306047256, 60822.0, 60838.695806520685, 60840.39159608436, 60917.39082609206, 64527.67736161289, 64651.38371540749, 64683.353166468674, 64731.676341618935, 64816.35183648103, 15422.228888555528, 15619.453319133776, 15622.76565851513, 15623.843761562239, 15640.983336083345, 10897.291676041032, 11134.27627204229, 11137.498812553455, 12072.396380180955, 12154.453049612612, 19152.369960288925, 19842.50393740168, 19917.60164796725, 20402.97985100739, 20450.693239601416, 6731.0, 7987.640556174986, 7991.24083212094, 8003.319717823933, 8106.756797296091, 840.8738689196547, 843.7679638099572, 851.3104385447807, 916.4867674102524, 986.6201512417771, 29985.250368740955, 30073.849630751705, 30283.394332113676, 30369.24076898051, 30788.0, 899.5906862377602, 1013.8631284776461, 1032.5869652139204, 1045.5242864496636, 1076.5855145768778, 13789.24159171235, 15191.392344306325, 15731.056136631838, 15923.283452244677, 15930.283137258852, 615564.0, 618929.3374731953, 619546.0, 620683.0, 621160.0, 58681.0, 59040.0, 59169.0, 59171.0, 59249.70375148096, 1156.4969238381332, 1292.2052937443593, 1346.0981142634262, 1347.7506661267696, 1348.919064856092, 28868.566971495864, 29305.97429090057, 29362.853185733933, 30265.69734302717, 30390.0, 51716.24256361425, 52133.47866521438, 52220.21669674882, 52243.73878130737, 52264.0, 602524.0, 605105.0, 609026.0, 610402.0, 610494.0, 30791.53812692856, 35074.473882892285, 35122.0, 35335.45024026405, 42487.5751242496, 39440.01399965024, 47094.76452617852, 48527.5147248523, 48636.75681621569, 48737.7815554614, 29357.85321073381, 35024.824875875456, 37631.81184094062, 38883.61116388913, 42171.10230039594, 38142.61857381391, 38207.808960955015, 38542.80728596339, 39554.802225988686, 40186.39720404138, 38050.619493804705, 38052.42921356126, 38703.22593548169, 38879.22241555097, 39208.607913920496, 27341.0, 32769.01692949215, 33892.49161262534, 34749.957501275, 34852.65147348595, 24615.12316058844, 25044.123455678964, 25163.119290824732, 25750.613740794277, 25872.482550349265], "value": 20431.691268712264, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.2328866190193, 353.5368667046168, 362.1417241138502, 371.90702324418885, 387.21588782714997, 14595.854041459583, 14778.0, 15372.000819946723, 15437.305321260543, 18716.719249211274, 18682.06589670518, 18801.717974230363, 19195.808041919576, 20031.499212519695, 20279.59440811183, 4627.768611569417, 5964.88070238595, 5977.163197152402, 5977.551683623724, 5990.191324171246, 5649.548036157097, 5807.0, 5826.592138550303, 5886.470217680408, 5981.0729336952845, 5823.767049318022, 5826.184334193216, 5827.504662103705, 5836.387179346171, 5842.88314233715, 5619.80330688424, 5712.428757124296, 5738.0532212184935, 5812.157237200603, 5818.854528636775, 5075.873103172414, 5081.390233172006, 5081.79672813087, 5085.516875896784, 5099.923501147486, 19729.802701973083, 23604.7639523606, 23760.524789504285, 23856.403589910216, 24425.022999080193, 1892.6782446984046, 2223.2885476647316, 2235.16181431963, 2295.3114065780364, 2461.5815311396927, 14352.92823535886, 14542.418303267857, 14672.559823205322, 15086.39654413822, 15153.154136625546, 1676.6311411489453, 1799.2263326769485, 1833.6607727570442, 1851.4815851561584, 1895.3745264062964, 99871.0, 104632.47683761608, 105222.0, 106343.0, 106381.91507443754, 10177.949110254549, 10307.433091179908, 10468.47657617116, 10757.462126893624, 10898.237123401286, 13263.535776247785, 13286.468541258435, 13292.136011159244, 13318.600441986757, 13321.06752527333, 7745.535267883945, 8037.517748935083, 8046.839063218703, 8153.184681531918, 8182.509049457051, 6594.505412094016, 7777.766666999999, 7823.765287041398, 7863.33161681252, 8007.959960200162, 6388.105665206875, 6502.60984340941, 6504.479641628658, 6527.54307198501, 6618.53670243088, 1826.6529359421668, 1858.6654402207462, 1859.6094820087662, 1861.0087206688984, 1863.7390765292869, 829.4981536170651, 906.5467266366817, 940.304174910575, 941.7786820097272, 1027.291169093323, 19502.12240449183, 21686.891565542388, 21724.782752172276, 21772.564548709255, 21785.564288714453, 792.4690457393444, 905.4250550900226, 916.5005072235648, 940.1444685336447, 991.7966816802682, 9279.62881484733, 10424.322419042737, 10878.292910960763, 11027.944860275647, 11072.889271107348, 585897.0, 590058.0, 595559.0, 604135.0, 606297.0, 98115.05654830366, 101037.28735609182, 112124.0, 132025.0, 135798.32100839433, 936.4287784451479, 953.6566835939057, 997.0926456924165, 999.1107913956585, 1001.379144930148, 18786.342478013205, 19149.712754308974, 19188.0, 19270.22919083221, 19381.90309048446, 38342.0, 48121.0, 53103.734481327345, 53899.461005391015, 55434.61413464696, 51967.440976770755, 73500.63249683718, 76043.23956760361, 76993.0, 78536.60731696305, 7698.114716807415, 7829.29536341732, 7972.521648701097, 8044.1955804419085, 8080.797980050311, 9555.61777528891, 10095.39427634315, 11997.220180688228, 12032.639020829389, 12352.691182720144, 10734.087602553871, 10745.516451759533, 10774.515146818256, 10848.0, 10908.181886358396, 10579.047885690325, 10601.045905868508, 10632.30889992148, 10800.891991079989, 10944.835827462435, 10841.243992533005, 11176.832347514957, 11185.496652650812, 11215.775684486101, 11220.66338009861, 7126.608036557832, 7807.258310460497, 7905.723299684483, 7931.643076061476, 7966.0, 9569.521523923637, 10425.270231084054, 10433.843492347773, 10687.342415514859, 10727.302725322828], "value": 9300.763369163502, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1285.8842704156625, 1469.1992863889182, 1592.3710134496873, 1774.1572752942361, 1825.607494388707, 6777.390034896858, 8412.411131220817, 8413.873791893118, 8420.621072051757, 8432.451890627104, 7501.662425190866, 7741.0, 7987.801829725544, 8190.2628763411285, 8246.381521385905, 2274.0, 2305.6426253930667, 2305.8847057647135, 2308.1113771198125, 2312.387217387394, 2297.6208925527353, 2319.6172631515865, 2342.9648555271683, 2343.6953196084487, 2350.059976009593, 1965.8918759468224, 2290.942726431836, 2291.521968330426, 2316.51353215824, 2317.2584772872688, 2261.5589959957815, 2272.659101134843, 2295.357299956015, 2297.3452566018686, 2303.4011157099276, 2288.7482376938524, 2317.1310758465575, 2318.13070098713, 2318.594246006959, 2322.396176993994, 8271.33829293655, 8314.625841837133, 8378.413511054228, 8381.874271885927, 8389.161083891622, 5028.622853285981, 5186.962607478519, 5199.818006369759, 5202.895942081175, 5211.921821172686, 3091.2580980564726, 3207.695268949446, 3210.1493104327374, 3551.6093229744706, 3556.626554211822, 1012.6405126180174, 1034.57065317893, 1051.9631812886512, 1056.4770438632927, 1057.1542765787417, 9105.681301154427, 10742.099633099098, 10804.459777011118, 10926.99999999992, 10968.17738669595, 3776.546814382292, 4401.339799030171, 4601.056783359403, 4604.447466304065, 4678.274867395559, 9363.719088427359, 9365.063493650581, 9367.250619950524, 9375.390599611004, 9376.296777741696, 5187.144121219956, 5188.351456067991, 5189.040027594831, 5191.402988656278, 5192.688438693691, 4266.274733295348, 4397.780110994438, 4603.631709463235, 4736.881577960509, 4747.430308363018, 3717.20080182762, 3732.5894151643297, 3844.692424606025, 3845.576986531479, 3887.416887466846, 1246.650937737435, 1253.9686507837378, 1256.8931640810451, 1266.316189257809, 1273.3505911984955, 806.306934837645, 839.7438781171679, 889.7997950461224, 891.3804905590682, 945.8271743038582, 5370.496261046913, 5420.159875219348, 5662.745176467069, 5711.485966263056, 5784.855378615484, 949.3307218411142, 962.2350231565782, 980.6420656460341, 984.6406061787396, 999.9550020249106, 3740.9064773380337, 3749.512563366745, 3751.962480375216, 3865.9226815463535, 3894.6105389460827, 9630.0, 10009.949950250348, 10130.746731331628, 10161.441120738282, 10162.136218421369, 10074.597016119273, 10075.496225188712, 10082.899171008195, 10088.243381746252, 10115.988401159972, 7717.459777815499, 7750.728724494616, 7795.376369890395, 7870.44906856526, 7888.684452621831, 5409.675419474844, 5435.701036442875, 5534.584906131976, 5594.692291923983, 5611.971940140274, 9414.95292523533, 9435.433873967584, 9442.055794420778, 9445.338826282094, 9446.29152813528, 9176.0, 9386.765330866783, 9557.378770379903, 9679.758006049906, 9680.031996800546, 6313.273973492924, 6517.120188774455, 6526.804195874131, 6563.606183628997, 6758.560693554903, 7802.921970780447, 8059.798505037186, 8063.27430531255, 8105.513669179868, 8121.593920303843, 6930.801352157367, 7094.893576596252, 7121.964390178223, 7173.713051477883, 7192.029076074663, 6719.496037797087, 6757.898631520629, 6984.615846128511, 7042.366187043191, 7052.118485189351, 6134.355892631388, 6300.59046161998, 6397.8720425592155, 6399.424051835357, 6418.839529011812, 3853.5183102112237, 4841.927371089507, 4893.388326459192, 4894.779734911866, 4942.8517144484285, 4770.332153498443, 4778.402699662543, 4897.8285759998225, 4931.852044438672, 5050.116229659869], "value": 4381.870667784932, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [252.8520815323036, 293.9978744748266, 305.18819938962355, 310.3931813304989, 312.16651540387164, 4304.923769057736, 4516.035524546816, 5120.641555091145, 5149.845504634857, 5233.2150177473395, 4336.631386332166, 4744.0, 4776.37907072081, 4947.180107338819, 5146.944876300358, 3766.886993390203, 3811.5235595550557, 3827.2536855313224, 3857.32496813058, 3878.45701601776, 3867.535895692507, 3910.9413358799643, 3921.8235179416993, 3924.1955399143108, 3947.3486874665646, 3305.752068594853, 3792.58281589025, 3854.807259637021, 3863.43980122882, 3900.609939006105, 3545.96107238339, 3867.323218436762, 3880.670143037817, 3901.356276214421, 3971.62269584389, 2935.722960512184, 3252.317013427183, 3337.1990722226733, 3340.5657264555693, 3347.2301370684877, 4940.184095065814, 4972.328735620681, 4974.726390048546, 4977.352944117241, 5028.429814831474, 4134.4831896013, 4431.379606855043, 4442.888927776799, 4445.799939002753, 4454.37638730578, 4537.978954735158, 5342.412334643186, 5346.946530534684, 5417.485338892799, 5418.512333889968, 3257.625373082102, 3391.9491207631904, 3518.736094792901, 3712.0, 3758.361078616642, 5362.0, 5375.328083989462, 5415.83752487426, 5608.4111168327145, 5608.579356548275, 3727.142757165867, 3737.4580685800406, 3747.887563373103, 3749.4375843623675, 3752.2683076799763, 3816.751911125768, 3822.7515211511163, 3828.693704503633, 3831.8084095795098, 3842.4438102568506, 4303.311470164758, 4438.669698408361, 4451.287793952952, 4455.732656040648, 4473.955260447419, 5140.34561981817, 5406.945930540723, 5419.593530485229, 5449.1826226066405, 5487.341519017744, 1144.9084073274119, 1152.2970987697497, 1285.8328417305877, 1289.7484990426965, 1299.876511731384, 717.1967396515936, 834.3158609939912, 839.6230182500703, 851.9105493923173, 875.5228400521731, 4463.776811159429, 4641.094986477672, 5022.673526220784, 5047.545720885065, 5057.31726216956, 5018.68766614033, 5445.945540544623, 5463.672179669153, 5468.343798744097, 5516.972415137899, 3978.303796835542, 4261.397690494258, 4833.806647734122, 4849.80600775965, 5070.923936140961, 2860.5709143628624, 2860.599516067752, 2862.9284267893468, 2871.6410448693914, 2882.466743652431, 5072.746362681851, 5318.760655770499, 5454.527277635096, 5588.01625422969, 5624.0, 4661.440627124767, 4702.542211914318, 4900.901981960341, 5513.889722205534, 5618.887622247532, 5590.608657393944, 5597.804076857372, 5619.185218143346, 5654.717264136859, 5677.091665333444, 4941.876453088702, 4953.2570114483115, 5366.785328586813, 5396.946030539644, 5457.863553411196, 5328.8401347959625, 5348.705821179872, 5349.491798279158, 5356.6785992838995, 5373.946260537345, 4677.929831052605, 5035.525487413673, 5333.146696528537, 5580.86047848807, 5639.577031722555, 3285.5071739239306, 3513.771604845677, 3519.753617246768, 3663.2782592181634, 3678.889633311005, 4867.95132048675, 5322.54758345545, 5401.243825864303, 5434.592405569519, 5543.61194716366, 4061.817218225246, 4911.729854857874, 5087.465816089405, 5284.656497327662, 5288.8148914789335, 4464.888377790581, 5155.948440515697, 5299.973500132474, 5300.867478313073, 5326.840194794162, 4583.633309335179, 5027.622928280321, 5092.860998380615, 5132.2798916574275, 5173.430922598586, 4408.933865992077, 4540.455145382575, 5203.479652034766, 5232.979568984011, 5315.681059136464, 4753.762311884461, 5251.52736253739, 5264.552513036435, 5276.736163191902, 5292.623917781481], "value": 3889.834431157367, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.247221819889, 727.7052793618585, 779.8908152858601, 794.3724457678435, 808.6563210635478, 16175.919120404396, 16714.916425417858, 16718.582035449104, 16726.49820505384, 16808.495745127664, 15415.768763468557, 15525.0, 15553.611159721013, 15586.376544938217, 15600.141992190425, 633.8668879535303, 835.3150416658327, 857.8284343131368, 859.660434128522, 859.7635650196215, 693.0609024771438, 827.5159031966277, 845.4208866926175, 851.5912362066211, 856.755824589992, 792.3423558446505, 903.9276857851356, 905.5653286422521, 906.6871929184421, 907.6505545365048, 897.2776914583758, 899.0964081098487, 900.0909081827368, 901.5627420700955, 902.9638814447414, 525.5349016120722, 539.4551502981977, 642.983925401864, 660.9919872194893, 662.867426514699, 14681.192534410604, 14712.485063022743, 14722.63193420173, 14732.631684207874, 14736.410543578244, 12556.99544036484, 12658.746825063447, 12915.806262906064, 12923.0, 13122.14706044104, 11792.764144717143, 12035.09736769737, 12075.516979320817, 12084.395780211042, 12682.858542731106, 4337.522872484024, 4345.73925564464, 4455.576720211575, 4540.4551453825425, 4558.817647294104, 91408.08591914129, 91542.5422872888, 92897.60653590201, 93787.0, 94038.0, 8906.332025098143, 10143.442110683984, 10228.437435940947, 10549.947250263853, 10696.251262411712, 19321.323753668836, 19406.611867762567, 19408.61182776365, 19427.902860485607, 19433.0, 7330.486865919334, 7362.190159082496, 7403.0, 7519.924800752032, 7624.504407213513, 4132.383063776143, 4230.936535951963, 4317.676174286941, 4332.523422423531, 4337.934930976038, 3411.556497655289, 3507.3686736387695, 3515.876944306937, 3518.3491054155065, 3522.5420695309444, 637.5441559285133, 639.0861068671751, 645.1612903225788, 646.7930262316107, 651.9641419721866, 542.6255883440492, 598.8293336399105, 602.217117746936, 614.9729950981871, 714.731975509184, 8581.785455363666, 8691.478511289342, 9717.46553939526, 9861.211103111735, 9866.802663946824, 592.8577141486012, 650.8535579494576, 672.3444641474621, 674.652553934724, 688.3770187979886, 3530.593981692087, 4074.5925407459017, 4075.572064933199, 4529.501754806968, 4660.557247061523, 71042.9343559847, 72278.0, 103067.0, 107311.0495635578, 112608.43695781469, 74999.87500187501, 80409.0, 97009.51495242478, 97456.51271743597, 99541.0, 56196.43803561994, 68074.0, 73600.0, 79723.20276797273, 87996.5600171995, 9464.337496375188, 9652.9034709652, 9655.951720241354, 9677.661281855102, 9679.612815487302, 52898.0, 53273.742569901726, 54085.459145409615, 54761.0, 56005.719971399885, 69109.30890691029, 72782.23196056057, 77155.61422192852, 77853.61073194824, 78504.21495784969, 11880.584179553674, 12085.395730213279, 12106.455209515418, 12205.450754716237, 12441.253524788543, 13962.58112256634, 13963.720725585634, 14282.785758213842, 14394.568162955127, 14433.711325773636, 12795.552155674506, 13114.267186115303, 13133.868661313265, 13147.342632868127, 13215.0, 10453.634122805664, 13090.083694141318, 13123.475060997453, 13315.53395631187, 13758.793618095937, 12765.36173191318, 12779.36103194818, 12872.0, 13035.934820325838, 13063.216207027608, 10286.125679317342, 10875.293105948089, 10945.890541094486, 11324.980751732384, 11347.37589432589, 10946.945265273622, 11017.614383496539, 11027.779444410906, 11139.220254582426, 11203.831942521032], "value": 7333.821579019964, "unit": "score"}], "name": "dromaeo_css", "value": 7133.625319871413, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 113.0, 112.0, 111.0, 112.0, 113.0, 112.0, 112.0, 114.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 114.0, 137.0, 170.0, 112.0, 106.0, 113.0, 114.0, 153.0, 138.0], "value": 130.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 181.0, 180.0, 180.0, 185.0, 185.0, 181.0, 178.0, 182.0, 182.0], "value": 181.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 58.0, 90.0, 62.0, 59.0, 59.0, 57.0, 80.0, 57.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 91.0, 71.0, 68.0, 69.0, 92.0, 71.0, 71.0, 94.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 85.0, 87.0, 84.0, 88.0, 84.0, 87.0, 83.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 97.0, 93.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 79.0, 89.0, 131.0, 122.0, 83.0, 81.0, 80.0, 80.0], "value": 91.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 53.0, 55.0, 55.0, 56.0, 55.0, 55.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 80.0, 95.0, 115.0, 88.0, 69.0, 69.0, 71.0, 68.0, 94.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 107.0, 107.0, 98.0, 86.0, 87.0, 84.0, 89.0, 87.0, 88.0], "value": 92.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 162.0, 161.0, 157.0, 151.0, 141.0, 152.0, 214.0, 151.0], "value": 158.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 239.0, 198.0, 171.0, 233.0, 207.0, 172.0, 172.0, 175.0], "value": 191.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 67.0, 65.0, 67.0, 67.0, 65.0, 66.0, 64.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1485.6000000000001, "alertThreshold": 2.0}]} 17:21:08 INFO - Return code: 0 17:21:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:21:08 INFO - # TBPL SUCCESS # 17:21:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:21:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:21:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:21:08 INFO - 2016-11-02 17:21:08.854 screenresolution[1673:11241] starting screenresolution argv=screenresolution get 17:21:08 INFO - 2016-11-02 17:21:08.857 screenresolution[1673:11241] Display 0: 1600x1200x32@60 17:21:08 INFO - 2016-11-02 17:21:08.863 screenresolution[1674:11244] starting screenresolution argv=screenresolution list 17:21:08 INFO - Available Modes on Display 0 17:21:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:21:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:21:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:21:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:21:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:21:09 INFO - Intel Iris: 17:21:09 INFO - Chipset Model: Intel Iris 17:21:09 INFO - Type: GPU 17:21:09 INFO - Bus: Built-In 17:21:09 INFO - VRAM (Dynamic, Max): 1536 MB 17:21:09 INFO - Vendor: Intel (0x8086) 17:21:09 INFO - Device ID: 0x0a2e 17:21:09 INFO - Revision ID: 0x0009 17:21:09 INFO - Displays: 17:21:09 INFO - Display: 17:21:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:21:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:21:09 INFO - Display Serial Number: 1600x1200 60 17:21:09 INFO - Main Display: Yes 17:21:09 INFO - Mirror: Off 17:21:09 INFO - Online: Yes 17:21:09 INFO - Rotation: Supported 17:21:09 INFO - Return code: 0 17:21:09 INFO - Running post-action listener: _resource_record_post_action 17:21:09 INFO - [mozharness: 2016-11-03 00:21:09.110174Z] Finished run-tests step (success) 17:21:09 INFO - Running post-run listener: _resource_record_post_run 17:21:09 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 32185344; Write bytes: 710322176; Read time: 195; Write time: 2013 17:21:09 INFO - TinderboxPrint: CPU usage
53.6% 17:21:09 INFO - TinderboxPrint: I/O read bytes / time
32,185,344 / 195 17:21:09 INFO - TinderboxPrint: I/O write bytes / time
710,322,176 / 2,013 17:21:09 INFO - TinderboxPrint: CPU idle
2,253.9 (46.4%) 17:21:09 INFO - TinderboxPrint: CPU system
971.3 (20.0%) 17:21:09 INFO - TinderboxPrint: CPU user
1,632.5 (33.6%) 17:21:09 INFO - TinderboxPrint: Swap in / out
435,146,752 / 0 17:21:09 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 408011264; Write bytes: 402360320; Read time: 29790; Write time: 863 17:21:09 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 29088768; Write bytes: 298569728; Read time: 173; Write time: 1116 17:21:09 INFO - Running post-run listener: _upload_blobber_files 17:21:09 INFO - Blob upload gear active. 17:21:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:21:09 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 17:21:09 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:21:09 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:21:09 INFO - (blobuploader) - INFO - Open directory for files ... 17:21:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:21:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:21:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:21:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:21:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:21:11 INFO - (blobuploader) - INFO - Done attempting. 17:21:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:21:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:21:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:21:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:21:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:21:14 INFO - (blobuploader) - INFO - Done attempting. 17:21:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:21:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:21:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:21:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:21:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:21:16 INFO - (blobuploader) - INFO - Done attempting. 17:21:16 INFO - (blobuploader) - INFO - Iteration through files over. 17:21:16 INFO - Return code: 0 17:21:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:21:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:21:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/7938ba76618e5315b975a40502b47faa103a6a01ae8175d7bb822a6cbd795c39a731aac4d4ea3421f1cdd34b422003710edfd3f7385a79f50c4436b9584e50dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/dfa4dfd8ffced78eb418209f1083c371c6a80d1131d0168968c3db4eede136cea6ded67324186b573400f62d7b604bfb8f4e9ef62d10b2f52182f8b538b61cc5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f3fca567f2bcf04d91a6759afeaaf9e2e610e19d561064d5ce677775e640c2d3c2645ef1895d75449677bb72cb154c2f60c34412b3e8653d5539977eefd7a001"} 17:21:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:21:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:21:16 INFO - Contents: 17:21:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/7938ba76618e5315b975a40502b47faa103a6a01ae8175d7bb822a6cbd795c39a731aac4d4ea3421f1cdd34b422003710edfd3f7385a79f50c4436b9584e50dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/dfa4dfd8ffced78eb418209f1083c371c6a80d1131d0168968c3db4eede136cea6ded67324186b573400f62d7b604bfb8f4e9ef62d10b2f52182f8b538b61cc5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f3fca567f2bcf04d91a6759afeaaf9e2e610e19d561064d5ce677775e640c2d3c2645ef1895d75449677bb72cb154c2f60c34412b3e8653d5539977eefd7a001"} 17:21:16 INFO - Running post-run listener: copy_logs_to_upload_dir 17:21:16 INFO - Copying logs to upload dir... 17:21:16 INFO - mkdir: /builds/slave/test/build/upload/logs 17:21:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.681732 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-02 17:21:16.507527) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-02 17:21:16.511604) ========= 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.RSLj4b7vUe/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.uNmwONNKyK/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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/Cedar/sha512/7938ba76618e5315b975a40502b47faa103a6a01ae8175d7bb822a6cbd795c39a731aac4d4ea3421f1cdd34b422003710edfd3f7385a79f50c4436b9584e50dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/dfa4dfd8ffced78eb418209f1083c371c6a80d1131d0168968c3db4eede136cea6ded67324186b573400f62d7b604bfb8f4e9ef62d10b2f52182f8b538b61cc5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f3fca567f2bcf04d91a6759afeaaf9e2e610e19d561064d5ce677775e640c2d3c2645ef1895d75449677bb72cb154c2f60c34412b3e8653d5539977eefd7a001"} build_url:https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011227 build_url: 'https://queue.taskcluster.net/v1/task/S3VIcQ7_QF2bboAZtwd5mQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/7938ba76618e5315b975a40502b47faa103a6a01ae8175d7bb822a6cbd795c39a731aac4d4ea3421f1cdd34b422003710edfd3f7385a79f50c4436b9584e50dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/dfa4dfd8ffced78eb418209f1083c371c6a80d1131d0168968c3db4eede136cea6ded67324186b573400f62d7b604bfb8f4e9ef62d10b2f52182f8b538b61cc5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f3fca567f2bcf04d91a6759afeaaf9e2e610e19d561064d5ce677775e640c2d3c2645ef1895d75449677bb72cb154c2f60c34412b3e8653d5539977eefd7a001"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-02 17:21:16.544157) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 17:21:16.544490) ========= 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.RSLj4b7vUe/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.uNmwONNKyK/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.005273 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 17:21:16.593243) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-02 17:21:16.593560) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-02 17:21:16.593882) ========= ========= Total master_lag: 0.17 =========