builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0093 starttime: 1476361276.63 results: success (0) buildid: 20161013030023 builduid: 9170fed69e4a40cfbd9f81e241c1d041 revision: f03e2740d604d339ed553dad62a3fc54c317f8fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 05:21:16.631673) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 05:21:16.632090) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 05:21:16.632360) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 05:21:16.668196) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:21:16.668569) ========= 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.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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-10-13 05:21:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.1M=0.001s 2016-10-13 05:21:16 (12.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.112285 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:21:16.805924) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:21:16.806242) ========= 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.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.028891 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:21:16.854976) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 05:21:16.855317) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f03e2740d604d339ed553dad62a3fc54c317f8fa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f03e2740d604d339ed553dad62a3fc54c317f8fa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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-10-13 05:21:16,934 truncating revision to first 12 chars 2016-10-13 05:21:16,934 Setting DEBUG logging. 2016-10-13 05:21:16,934 attempt 1/10 2016-10-13 05:21:16,934 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f03e2740d604?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 05:21:18,195 unpacking tar archive at: fx-team-f03e2740d604/testing/mozharness/ program finished with exit code 0 elapsedTime=1.521641 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 05:21:18.400753) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 05:21:18.404107) ========= script_repo_revision: f03e2740d604d339ed553dad62a3fc54c317f8fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 05:21:18.404493) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 05:21:18.404749) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 05:21:18.423954) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-10-13 05:21:18.424333) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:21:18 INFO - MultiFileLogger online at 20161013 05:21:18 in /builds/slave/test 05:21:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:21:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:21:18 INFO - {'append_to_log': False, 05:21:18 INFO - 'base_work_dir': '/builds/slave/test', 05:21:18 INFO - 'blob_upload_branch': 'Fx-Team', 05:21:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:21:18 INFO - 'branch': 'Fx-Team', 05:21:18 INFO - 'buildbot_json_path': 'buildprops.json', 05:21:18 INFO - 'config_files': ('talos/mac_config.py',), 05:21:18 INFO - 'default_actions': ('clobber', 05:21:18 INFO - 'read-buildbot-config', 05:21:18 INFO - 'download-and-extract', 05:21:18 INFO - 'populate-webroot', 05:21:18 INFO - 'create-virtualenv', 05:21:18 INFO - 'install', 05:21:18 INFO - 'run-tests'), 05:21:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:21:18 INFO - 'download_minidump_stackwalk': True, 05:21:18 INFO - 'download_symbols': 'ondemand', 05:21:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:21:18 INFO - 'tooltool.py': '/tools/tooltool.py', 05:21:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:21:18 INFO - '/tools/misc-python/virtualenv.py')}, 05:21:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:21:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:21:18 INFO - 'installer_path': 'installer.exe', 05:21:18 INFO - 'log_level': 'info', 05:21:18 INFO - 'log_name': 'talos', 05:21:18 INFO - 'log_to_console': True, 05:21:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:21:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:21:18 INFO - 'opt_config_files': (), 05:21:18 INFO - 'pip_index': False, 05:21:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:21:18 INFO - 'cmd': ('bash', 05:21:18 INFO - '-c', 05:21:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:21:18 INFO - 'enabled': True, 05:21:18 INFO - 'halt_on_failure': False, 05:21:18 INFO - 'name': 'check_screen_resolution'},), 05:21:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:21:18 INFO - 'cmd': ('bash', 05:21:18 INFO - '-c', 05:21:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:21:18 INFO - 'enabled': True, 05:21:18 INFO - 'halt_on_failure': False, 05:21:18 INFO - 'name': 'check_screen_resolution'},), 05:21:18 INFO - 'run_cmd_checks_enabled': True, 05:21:18 INFO - 'sps_profile': False, 05:21:18 INFO - 'sps_profile_interval': 0, 05:21:18 INFO - 'suite': 'dromaeojs', 05:21:18 INFO - 'system_bits': '32', 05:21:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:21:18 INFO - 'title': 't-yosemite-r7-0093', 05:21:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:21:18 INFO - 'use_talos_json': True, 05:21:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:21:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:21:18 INFO - 'work_dir': 'build'} 05:21:18 INFO - [mozharness: 2016-10-13 12:21:18.648438Z] Running clobber step. 05:21:18 INFO - Running pre-action listener: _resource_record_pre_action 05:21:18 INFO - Running main action method: clobber 05:21:18 INFO - rmtree: /builds/slave/test/build 05:21:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:21:21 INFO - Running post-action listener: _resource_record_post_action 05:21:21 INFO - [mozharness: 2016-10-13 12:21:21.022697Z] Finished clobber step (success) 05:21:21 INFO - [mozharness: 2016-10-13 12:21:21.022832Z] Running read-buildbot-config step. 05:21:21 INFO - Running pre-action listener: _resource_record_pre_action 05:21:21 INFO - Running main action method: read_buildbot_config 05:21:21 INFO - Using buildbot properties: 05:21:21 INFO - { 05:21:21 INFO - "project": "", 05:21:21 INFO - "product": "firefox", 05:21:21 INFO - "script_repo_revision": "production", 05:21:21 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:21:21 INFO - "repository": "", 05:21:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:21:21 INFO - "buildid": "20161013030023", 05:21:21 INFO - "pgo_build": "False", 05:21:21 INFO - "basedir": "/builds/slave/test", 05:21:21 INFO - "buildnumber": 17, 05:21:21 INFO - "slavename": "t-yosemite-r7-0093", 05:21:21 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:21:21 INFO - "platform": "yosemite_r7", 05:21:21 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:21:21 INFO - "branch": "fx-team", 05:21:21 INFO - "repo_path": "integration/fx-team", 05:21:21 INFO - "revision": "f03e2740d604d339ed553dad62a3fc54c317f8fa", 05:21:21 INFO - "stage_platform": "macosx64", 05:21:21 INFO - "builduid": "9170fed69e4a40cfbd9f81e241c1d041", 05:21:21 INFO - "slavebuilddir": "test" 05:21:21 INFO - } 05:21:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:21:21 INFO - Running post-action listener: _resource_record_post_action 05:21:21 INFO - [mozharness: 2016-10-13 12:21:21.026134Z] Finished read-buildbot-config step (success) 05:21:21 INFO - [mozharness: 2016-10-13 12:21:21.026293Z] Running download-and-extract step. 05:21:21 INFO - Running pre-action listener: _resource_record_pre_action 05:21:21 INFO - Running main action method: download_and_extract 05:21:21 INFO - mkdir: /builds/slave/test/build/tests 05:21:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:21 INFO - trying https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:21:21 INFO - Downloading https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/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 05:21:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/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 05:21:22 INFO - Downloaded 1199 bytes. 05:21:22 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:21:22 INFO - Using the following test package requirements: 05:21:22 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:21:22 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:22 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:21:22 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:22 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:21:22 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:22 INFO - u'jsshell-mac.zip'], 05:21:22 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:22 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:21:22 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:21:22 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:22 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:21:22 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:22 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:21:22 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:22 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:21:22 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:21:22 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:21:22 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:21:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:21:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:21:22 INFO - Fetch https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:21:22 INFO - Http code: 200 05:21:22 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 05:21:22 INFO - x-amz-cf-id: YUIobdu4dsTaFESMGaTeq1WI5EsiNd-mOHDq469PxlzPEqVOZlv4iQ== 05:21:22 INFO - x-amz-version-id: YTSl_I412y0kPBA6QJynUNG5Hu7Cko5G 05:21:23 INFO - Expected file size: 16520378 05:21:23 INFO - Obtained file size: 16520378 05:21:29 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 05:21:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:21:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:21:29 INFO - Fetch https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:21:30 INFO - Http code: 200 05:21:30 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 05:21:30 INFO - x-amz-cf-id: Ebc2cPDX8xAFnY7Lrie5d_tEgsqKnlr96-BCrItvIYLhOuU2TiUPtw== 05:21:30 INFO - x-amz-version-id: YTSl_I412y0kPBA6QJynUNG5Hu7Cko5G 05:21:30 INFO - Expected file size: 16520378 05:21:30 INFO - Obtained file size: 16520378 05:21:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:21:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:21:37 INFO - Fetch https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:21:38 INFO - Http code: 200 05:21:38 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 05:21:38 INFO - x-amz-cf-id: akVpzu-jUXKj6KVJxDa7Re0oteV_mww0dmiiPx-nfNu19dEhVL3m-A== 05:21:38 INFO - x-amz-version-id: Fo5TMQl_WzHJR5HcRR0hAC88srZyS_wr 05:21:38 INFO - Expected file size: 14409006 05:21:38 INFO - Obtained file size: 14409006 05:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:39 INFO - trying https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:21:39 INFO - Downloading https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:21:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/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 05:21:42 INFO - Downloaded 111661380 bytes. 05:21:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:21:42 INFO - mkdir: /builds/slave/test/properties 05:21:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:21:42 INFO - Writing to file /builds/slave/test/properties/build_url 05:21:42 INFO - Contents: 05:21:42 INFO - build_url:https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:21:43 INFO - Running post-action listener: _resource_record_post_action 05:21:43 INFO - Running post-action listener: set_extra_try_arguments 05:21:43 INFO - [mozharness: 2016-10-13 12:21:43.383216Z] Finished download-and-extract step (success) 05:21:43 INFO - [mozharness: 2016-10-13 12:21:43.383340Z] Running populate-webroot step. 05:21:43 INFO - Running pre-action listener: _resource_record_pre_action 05:21:43 INFO - Running main action method: populate_webroot 05:21:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:21:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:21:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:21:43 INFO - '--tppagecycles', 05:21:43 INFO - '7'], 05:21:43 INFO - 'tests': ['tsvgm']}}, 05:21:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:21:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:21:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:21:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:21:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:21:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:21:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:43 INFO - 'talos_options': ['--disable-e10s'], 05:21:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:21:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:21:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:43 INFO - 'talos_options': ['--disable-e10s'], 05:21:43 INFO - 'tests': ['damp', 'tps']}, 05:21:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:43 INFO - 'tests': ['damp', 'tps']}, 05:21:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:21:43 INFO - 'tests': ['dromaeo_dom']}, 05:21:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:21:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:21:43 INFO - 'tests': ['basic_compositor_video']}, 05:21:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:21:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:21:43 INFO - 'tests': ['a11yr', 05:21:43 INFO - 'ts_paint', 05:21:43 INFO - 'tpaint', 05:21:43 INFO - 'sessionrestore', 05:21:43 INFO - 'sessionrestore_no_auto_restore', 05:21:43 INFO - 'tabpaint']}, 05:21:43 INFO - 'other-e10s': {'tests': ['a11yr', 05:21:43 INFO - 'ts_paint', 05:21:43 INFO - 'tpaint', 05:21:43 INFO - 'sessionrestore', 05:21:43 INFO - 'sessionrestore_no_auto_restore', 05:21:43 INFO - 'tabpaint']}, 05:21:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:21:43 INFO - 'ts_paint', 05:21:43 INFO - 'tpaint', 05:21:43 INFO - 'sessionrestore', 05:21:43 INFO - 'sessionrestore_no_auto_restore', 05:21:43 INFO - 'tabpaint']}, 05:21:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:21:43 INFO - 'ts_paint', 05:21:43 INFO - 'tpaint', 05:21:43 INFO - 'sessionrestore', 05:21:43 INFO - 'sessionrestore_no_auto_restore', 05:21:43 INFO - 'tabpaint']}, 05:21:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:21:43 INFO - 'tests': ['a11yr', 05:21:43 INFO - 'ts_paint', 05:21:43 INFO - 'tpaint', 05:21:43 INFO - 'sessionrestore', 05:21:43 INFO - 'sessionrestore_no_auto_restore', 05:21:43 INFO - 'tabpaint']}, 05:21:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:21:43 INFO - 'tests': ['a11yr', 05:21:43 INFO - 'ts_paint', 05:21:43 INFO - 'tpaint', 05:21:43 INFO - 'sessionrestore', 05:21:43 INFO - 'sessionrestore_no_auto_restore', 05:21:43 INFO - 'tabpaint']}, 05:21:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:21:43 INFO - 'tests': ['tsvgx', 05:21:43 INFO - 'tsvgr_opacity', 05:21:43 INFO - 'tart', 05:21:43 INFO - 'tscrollx', 05:21:43 INFO - 'cart']}, 05:21:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:21:43 INFO - 'tsvgr_opacity', 05:21:43 INFO - 'tart', 05:21:43 INFO - 'tscrollx', 05:21:43 INFO - 'cart']}, 05:21:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:43 INFO - 'talos_options': ['--disable-e10s'], 05:21:43 INFO - 'tests': ['tp5o']}, 05:21:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:43 INFO - 'tests': ['tp5o']}, 05:21:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:43 INFO - 'talos_options': ['--disable-e10s', 05:21:43 INFO - '--xperf_path', 05:21:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:21:43 INFO - 'tests': ['tp5n']}, 05:21:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:43 INFO - 'talos_options': ['--xperf_path', 05:21:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:21:43 INFO - 'tests': ['tp5n']}}, 05:21:43 INFO - 'talos.zip': {'path': '', 05:21:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:21:43 INFO - Running post-action listener: _resource_record_post_action 05:21:43 INFO - [mozharness: 2016-10-13 12:21:43.402522Z] Finished populate-webroot step (success) 05:21:43 INFO - [mozharness: 2016-10-13 12:21:43.402638Z] Running create-virtualenv step. 05:21:43 INFO - Running pre-action listener: _resource_record_pre_action 05:21:43 INFO - Running main action method: create_virtualenv 05:21:43 INFO - Creating virtualenv /builds/slave/test/build/venv 05:21:43 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:21:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:21:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:21:43 INFO - Using real prefix '/tools/python27' 05:21:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:21:44 INFO - Installing distribute.............................................................................................................................................................................................done. 05:21:47 INFO - Installing pip.................done. 05:21:47 INFO - Return code: 0 05:21:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:21:47 INFO - Reading from file tmpfile_stdout 05:21:47 INFO - Output received: 05:21:47 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:21:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1056b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f0cb88c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054cdd30>, '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 0x1052f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054c5b20>, '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.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:21:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rqmcuHE2VL/Render', 05:21:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:47 INFO - 'HOME': '/Users/cltbld', 05:21:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:47 INFO - 'LOGNAME': 'cltbld', 05:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:47 INFO - 'MOZ_NO_REMOTE': '1', 05:21:47 INFO - 'NO_EM_RESTART': '1', 05:21:47 INFO - 'PAGER': '/bin/cat', 05:21:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:47 INFO - 'PWD': '/builds/slave/test', 05:21:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:47 INFO - 'SHELL': '/bin/bash', 05:21:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1Yfa5m7KDs/Listeners', 05:21:47 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:21:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:47 INFO - 'USER': 'cltbld', 05:21:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:47 INFO - 'XPC_FLAGS': '0x0', 05:21:47 INFO - 'XPC_SERVICE_NAME': '0', 05:21:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:21:47 INFO - Cleaning up... 05:21:47 INFO - Return code: 0 05:21:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1056b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f0cb88c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054cdd30>, '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 0x1052f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054c5b20>, '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.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:21:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rqmcuHE2VL/Render', 05:21:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:47 INFO - 'HOME': '/Users/cltbld', 05:21:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:47 INFO - 'LOGNAME': 'cltbld', 05:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:47 INFO - 'MOZ_NO_REMOTE': '1', 05:21:47 INFO - 'NO_EM_RESTART': '1', 05:21:47 INFO - 'PAGER': '/bin/cat', 05:21:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:47 INFO - 'PWD': '/builds/slave/test', 05:21:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:47 INFO - 'SHELL': '/bin/bash', 05:21:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1Yfa5m7KDs/Listeners', 05:21:47 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:21:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:47 INFO - 'USER': 'cltbld', 05:21:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:47 INFO - 'XPC_FLAGS': '0x0', 05:21:47 INFO - 'XPC_SERVICE_NAME': '0', 05:21:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:47 INFO - Downloading/unpacking psutil>=3.1.1 05:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:21:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:21:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:21:51 INFO - Installing collected packages: psutil 05:21:51 INFO - Running setup.py install for psutil 05:21:51 INFO - building 'psutil._psutil_osx' extension 05:21:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:21:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:21:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:21:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:21:52 INFO - building 'psutil._psutil_posix' extension 05:21:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:21:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:21:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:21:52 INFO - ^ 05:21:52 INFO - 1 warning generated. 05:21:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:21:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:21:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:21:52 INFO - Successfully installed psutil 05:21:52 INFO - Cleaning up... 05:21:52 INFO - Return code: 0 05:21:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:21:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:52 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 0x1056b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f0cb88c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054cdd30>, '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 0x1052f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054c5b20>, '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.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:21:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:21:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rqmcuHE2VL/Render', 05:21:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:52 INFO - 'HOME': '/Users/cltbld', 05:21:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:52 INFO - 'LOGNAME': 'cltbld', 05:21:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:52 INFO - 'MOZ_NO_REMOTE': '1', 05:21:52 INFO - 'NO_EM_RESTART': '1', 05:21:52 INFO - 'PAGER': '/bin/cat', 05:21:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:52 INFO - 'PWD': '/builds/slave/test', 05:21:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:52 INFO - 'SHELL': '/bin/bash', 05:21:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1Yfa5m7KDs/Listeners', 05:21:52 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:21:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:52 INFO - 'USER': 'cltbld', 05:21:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:52 INFO - 'XPC_FLAGS': '0x0', 05:21:52 INFO - 'XPC_SERVICE_NAME': '0', 05:21:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:21:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:21:55 INFO - Installing collected packages: mozsystemmonitor 05:21:55 INFO - Running setup.py install for mozsystemmonitor 05:21:55 INFO - Successfully installed mozsystemmonitor 05:21:55 INFO - Cleaning up... 05:21:55 INFO - Return code: 0 05:21:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:55 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 0x1056b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f0cb88c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054cdd30>, '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 0x1052f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054c5b20>, '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.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rqmcuHE2VL/Render', 05:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:55 INFO - 'HOME': '/Users/cltbld', 05:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:55 INFO - 'LOGNAME': 'cltbld', 05:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:55 INFO - 'MOZ_NO_REMOTE': '1', 05:21:55 INFO - 'NO_EM_RESTART': '1', 05:21:55 INFO - 'PAGER': '/bin/cat', 05:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:55 INFO - 'PWD': '/builds/slave/test', 05:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:55 INFO - 'SHELL': '/bin/bash', 05:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1Yfa5m7KDs/Listeners', 05:21:55 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:55 INFO - 'USER': 'cltbld', 05:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:55 INFO - 'XPC_FLAGS': '0x0', 05:21:55 INFO - 'XPC_SERVICE_NAME': '0', 05:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:55 INFO - Downloading/unpacking jsonschema==2.5.1 05:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:21:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:58 INFO - Downloading functools32-3.2.3-2.tar.gz 05:21:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:21:58 INFO - warning: no files found matching '*.txt' 05:21:58 INFO - no previously-included directories found matching 'build' 05:21:58 INFO - no previously-included directories found matching 'dist' 05:21:58 INFO - no previously-included directories found matching '.git*' 05:21:58 INFO - Installing collected packages: jsonschema, functools32 05:21:58 INFO - Running setup.py install for functools32 05:21:59 INFO - warning: no files found matching '*.txt' 05:21:59 INFO - no previously-included directories found matching 'build' 05:21:59 INFO - no previously-included directories found matching 'dist' 05:21:59 INFO - no previously-included directories found matching '.git*' 05:21:59 INFO - Successfully installed jsonschema functools32 05:21:59 INFO - Cleaning up... 05:21:59 INFO - Return code: 0 05:21:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:59 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 0x1056b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f0cb88c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054cdd30>, '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 0x1052f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054c5b20>, '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.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:21:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:21:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rqmcuHE2VL/Render', 05:21:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:59 INFO - 'HOME': '/Users/cltbld', 05:21:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:59 INFO - 'LOGNAME': 'cltbld', 05:21:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:59 INFO - 'MOZ_NO_REMOTE': '1', 05:21:59 INFO - 'NO_EM_RESTART': '1', 05:21:59 INFO - 'PAGER': '/bin/cat', 05:21:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:59 INFO - 'PWD': '/builds/slave/test', 05:21:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:59 INFO - 'SHELL': '/bin/bash', 05:21:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1Yfa5m7KDs/Listeners', 05:21:59 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:21:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:59 INFO - 'USER': 'cltbld', 05:21:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:59 INFO - 'XPC_FLAGS': '0x0', 05:21:59 INFO - 'XPC_SERVICE_NAME': '0', 05:21:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:21:59 INFO - Cleaning up... 05:21:59 INFO - Return code: 0 05:21:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:59 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 0x1056b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f0cb88c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054cdd30>, '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 0x1052f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054c5b20>, '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.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:21:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:21:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:21:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rqmcuHE2VL/Render', 05:21:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:59 INFO - 'HOME': '/Users/cltbld', 05:21:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:59 INFO - 'LOGNAME': 'cltbld', 05:21:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:59 INFO - 'MOZ_NO_REMOTE': '1', 05:21:59 INFO - 'NO_EM_RESTART': '1', 05:21:59 INFO - 'PAGER': '/bin/cat', 05:21:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:59 INFO - 'PWD': '/builds/slave/test', 05:21:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:59 INFO - 'SHELL': '/bin/bash', 05:21:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1Yfa5m7KDs/Listeners', 05:21:59 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:21:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:59 INFO - 'USER': 'cltbld', 05:21:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:59 INFO - 'XPC_FLAGS': '0x0', 05:21:59 INFO - 'XPC_SERVICE_NAME': '0', 05:21:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:59 INFO - Downloading/unpacking blobuploader==1.2.4 05:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:02 INFO - Downloading blobuploader-1.2.4.tar.gz 05:22:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:22:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:22:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:03 INFO - Downloading docopt-0.6.1.tar.gz 05:22:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:22:03 INFO - Installing collected packages: blobuploader, requests, docopt 05:22:03 INFO - Running setup.py install for blobuploader 05:22:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:22:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:22:03 INFO - Running setup.py install for requests 05:22:04 INFO - Running setup.py install for docopt 05:22:04 INFO - Successfully installed blobuploader requests docopt 05:22:04 INFO - Cleaning up... 05:22:04 INFO - Return code: 0 05:22:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:04 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 0x1056b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f0cb88c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054cdd30>, '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 0x1052f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054c5b20>, '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.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:22:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:22:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:22:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rqmcuHE2VL/Render', 05:22:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:04 INFO - 'HOME': '/Users/cltbld', 05:22:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:04 INFO - 'LOGNAME': 'cltbld', 05:22:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:04 INFO - 'MOZ_NO_REMOTE': '1', 05:22:04 INFO - 'NO_EM_RESTART': '1', 05:22:04 INFO - 'PAGER': '/bin/cat', 05:22:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:04 INFO - 'PWD': '/builds/slave/test', 05:22:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:04 INFO - 'SHELL': '/bin/bash', 05:22:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1Yfa5m7KDs/Listeners', 05:22:04 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:22:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:04 INFO - 'USER': 'cltbld', 05:22:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:04 INFO - 'XPC_FLAGS': '0x0', 05:22:04 INFO - 'XPC_SERVICE_NAME': '0', 05:22:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:22:04 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-LoQEI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:22:04 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-G3j_JA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:22:04 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-G4MNqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:22:04 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-wj0Ond-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:22:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-7niuzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:22:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-EQhk77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:22:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-6MhY1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:22:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-nS2slg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:22:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-tYI39j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:22:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-b00jlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:22:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Pm5cC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:22:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-l1KVDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:22:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-vH0EaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:22:06 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-OG9Ajy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:06 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-pfBGa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:22:06 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-vN3nu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:22:06 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-wWzWca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:22:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:22:06 INFO - Running setup.py install for manifestparser 05:22:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:22:06 INFO - Running setup.py install for mozcrash 05:22:06 INFO - Running setup.py install for mozdebug 05:22:06 INFO - Running setup.py install for mozdevice 05:22:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Running setup.py install for mozfile 05:22:07 INFO - Running setup.py install for mozhttpd 05:22:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Running setup.py install for mozinfo 05:22:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Running setup.py install for mozInstall 05:22:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Running setup.py install for mozleak 05:22:07 INFO - Running setup.py install for mozlog 05:22:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Running setup.py install for moznetwork 05:22:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:22:08 INFO - Running setup.py install for mozprocess 05:22:08 INFO - Running setup.py install for mozprofile 05:22:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:22:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:22:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:22:08 INFO - Running setup.py install for mozrunner 05:22:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:22:08 INFO - Running setup.py install for mozscreenshot 05:22:08 INFO - Running setup.py install for moztest 05:22:08 INFO - Running setup.py install for mozversion 05:22:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:22:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:22:09 INFO - Cleaning up... 05:22:09 INFO - Return code: 0 05:22:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:09 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 0x1056b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f0cb88c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054cdd30>, '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 0x1052f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054c5b20>, '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.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:22:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rqmcuHE2VL/Render', 05:22:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:09 INFO - 'HOME': '/Users/cltbld', 05:22:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:09 INFO - 'LOGNAME': 'cltbld', 05:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:09 INFO - 'MOZ_NO_REMOTE': '1', 05:22:09 INFO - 'NO_EM_RESTART': '1', 05:22:09 INFO - 'PAGER': '/bin/cat', 05:22:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:09 INFO - 'PWD': '/builds/slave/test', 05:22:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:09 INFO - 'SHELL': '/bin/bash', 05:22:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1Yfa5m7KDs/Listeners', 05:22:09 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:22:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:09 INFO - 'USER': 'cltbld', 05:22:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:09 INFO - 'XPC_FLAGS': '0x0', 05:22:09 INFO - 'XPC_SERVICE_NAME': '0', 05:22:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:22:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-hD7T8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:22:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-0YR20I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:22:09 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)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:22:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-H2QYrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:22:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-nFSGsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:22:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-WRn_Vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:22:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-6pFYCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:22:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-jslpWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:22:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-AgEQu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:22:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-CvgdcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:22:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-pZ1Z5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:22:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-mQ6FHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:22:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Ul41dW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:22:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-prayGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:22:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-RGA27H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:22:10 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)) 05:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-c9gt9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:22:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-aHgttW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:22:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-KqXe5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:22:11 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)) 05:22:11 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)) 05:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:22:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:13 INFO - Downloading blessings-1.6.tar.gz 05:22:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:22:13 INFO - Installing collected packages: blessings 05:22:13 INFO - Running setup.py install for blessings 05:22:13 INFO - Successfully installed blessings 05:22:13 INFO - Cleaning up... 05:22:14 INFO - Return code: 0 05:22:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:22:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:22:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:22:14 INFO - Reading from file tmpfile_stdout 05:22:14 INFO - Current package versions: 05:22:14 INFO - blessings == 1.6 05:22:14 INFO - blobuploader == 1.2.4 05:22:14 INFO - docopt == 0.6.1 05:22:14 INFO - functools32 == 3.2.3-2 05:22:14 INFO - jsonschema == 2.5.1 05:22:14 INFO - manifestparser == 1.1 05:22:14 INFO - mozInstall == 1.12 05:22:14 INFO - mozcrash == 0.17 05:22:14 INFO - mozdebug == 0.1 05:22:14 INFO - mozdevice == 0.48 05:22:14 INFO - mozfile == 1.2 05:22:14 INFO - mozhttpd == 0.7 05:22:14 INFO - mozinfo == 0.9 05:22:14 INFO - mozleak == 0.1 05:22:14 INFO - mozlog == 3.4 05:22:14 INFO - moznetwork == 0.27 05:22:14 INFO - mozprocess == 0.23 05:22:14 INFO - mozprofile == 0.28 05:22:14 INFO - mozrunner == 6.12 05:22:14 INFO - mozscreenshot == 0.1 05:22:14 INFO - mozsystemmonitor == 0.3 05:22:14 INFO - moztest == 0.7 05:22:14 INFO - mozversion == 1.4 05:22:14 INFO - psutil == 3.1.1 05:22:14 INFO - requests == 1.2.3 05:22:14 INFO - wsgiref == 0.1.2 05:22:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:15 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 0x1056b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f0cb88c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054cdd30>, '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 0x1052f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054c5b20>, '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.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:22:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:22:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:22:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rqmcuHE2VL/Render', 05:22:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:15 INFO - 'HOME': '/Users/cltbld', 05:22:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:15 INFO - 'LOGNAME': 'cltbld', 05:22:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:15 INFO - 'MOZ_NO_REMOTE': '1', 05:22:15 INFO - 'NO_EM_RESTART': '1', 05:22:15 INFO - 'PAGER': '/bin/cat', 05:22:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:15 INFO - 'PWD': '/builds/slave/test', 05:22:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:15 INFO - 'SHELL': '/bin/bash', 05:22:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1Yfa5m7KDs/Listeners', 05:22:15 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:22:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:15 INFO - 'USER': 'cltbld', 05:22:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:15 INFO - 'XPC_FLAGS': '0x0', 05:22:15 INFO - 'XPC_SERVICE_NAME': '0', 05:22:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:22:15 INFO - Cleaning up... 05:22:15 INFO - Return code: 0 05:22:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:22:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:15 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 0x1056b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f0cb88c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054cdd30>, '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 0x1052f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054c5b20>, '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.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:22:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:22:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:22:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rqmcuHE2VL/Render', 05:22:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:15 INFO - 'HOME': '/Users/cltbld', 05:22:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:15 INFO - 'LOGNAME': 'cltbld', 05:22:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:15 INFO - 'MOZ_NO_REMOTE': '1', 05:22:15 INFO - 'NO_EM_RESTART': '1', 05:22:15 INFO - 'PAGER': '/bin/cat', 05:22:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:15 INFO - 'PWD': '/builds/slave/test', 05:22:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:15 INFO - 'SHELL': '/bin/bash', 05:22:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1Yfa5m7KDs/Listeners', 05:22:15 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:22:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:15 INFO - 'USER': 'cltbld', 05:22:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:15 INFO - 'XPC_FLAGS': '0x0', 05:22:15 INFO - 'XPC_SERVICE_NAME': '0', 05:22:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:22:16 INFO - Cleaning up... 05:22:16 INFO - Return code: 0 05:22:16 INFO - Running post-action listener: _resource_record_post_action 05:22:16 INFO - Running post-action listener: _start_resource_monitoring 05:22:16 INFO - Starting resource monitoring. 05:22:16 INFO - [mozharness: 2016-10-13 12:22:16.069741Z] Finished create-virtualenv step (success) 05:22:16 INFO - [mozharness: 2016-10-13 12:22:16.070707Z] Running install step. 05:22:16 INFO - Running pre-action listener: _resource_record_pre_action 05:22:16 INFO - Running main action method: install 05:22:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:22:16 INFO - Reading from file tmpfile_stdout 05:22:16 INFO - Detecting whether we're running mozinstall >=1.0... 05:22:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:22:16 INFO - Reading from file tmpfile_stdout 05:22:16 INFO - Output received: 05:22:16 INFO - Usage: mozinstall [options] installer 05:22:16 INFO - Options: 05:22:16 INFO - -h, --help show this help message and exit 05:22:16 INFO - -d DEST, --destination=DEST 05:22:16 INFO - Directory to install application into. [default: 05:22:16 INFO - "/builds/slave/test"] 05:22:16 INFO - --app=APP Application being installed. [default: firefox] 05:22:16 INFO - mkdir: /builds/slave/test/build/application 05:22:16 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'] 05:22:16 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 05:22:51 INFO - Reading from file tmpfile_stdout 05:22:51 INFO - Output received: 05:22:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:22:51 INFO - Running post-action listener: _resource_record_post_action 05:22:51 INFO - [mozharness: 2016-10-13 12:22:51.387879Z] Finished install step (success) 05:22:51 INFO - [mozharness: 2016-10-13 12:22:51.388024Z] Running run-tests step. 05:22:51 INFO - Running pre-action listener: _resource_record_pre_action 05:22:51 INFO - Running main action method: run_tests 05:22:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:22:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:22:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:22:51 INFO - 2016-10-13 05:22:51.402 screenresolution[1642:7548] starting screenresolution argv=screenresolution get 05:22:51 INFO - 2016-10-13 05:22:51.405 screenresolution[1642:7548] Display 0: 1600x1200x32@60 05:22:51 INFO - 2016-10-13 05:22:51.412 screenresolution[1643:7550] starting screenresolution argv=screenresolution list 05:22:51 INFO - Available Modes on Display 0 05:22:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:22:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:22:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:22:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:22:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:22:51 INFO - Intel Iris: 05:22:51 INFO - Chipset Model: Intel Iris 05:22:51 INFO - Type: GPU 05:22:51 INFO - Bus: Built-In 05:22:51 INFO - VRAM (Dynamic, Max): 1536 MB 05:22:51 INFO - Vendor: Intel (0x8086) 05:22:51 INFO - Device ID: 0x0a2e 05:22:51 INFO - Revision ID: 0x0009 05:22:51 INFO - Displays: 05:22:51 INFO - Display: 05:22:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:22:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:22:51 INFO - Display Serial Number: 1600x1200 60 05:22:51 INFO - Main Display: Yes 05:22:51 INFO - Mirror: Off 05:22:51 INFO - Online: Yes 05:22:51 INFO - Rotation: Supported 05:22:51 INFO - Return code: 0 05:22:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:22:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:22:51 INFO - Python 2.7.3 05:22:51 INFO - Return code: 0 05:22:51 INFO - grabbing minidump binary from tooltool 05:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:51 INFO - 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 0x1054cdd30>, '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 0x1052f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054c5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:22:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:22:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:22:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:22:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:22:51 INFO - Return code: 0 05:22:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:22:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:22:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:22:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:22:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:22:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:22:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0093 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:22:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rqmcuHE2VL/Render', 05:22:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:51 INFO - 'HOME': '/Users/cltbld', 05:22:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:51 INFO - 'LOGNAME': 'cltbld', 05:22:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:22:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:22:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:51 INFO - 'MOZ_NO_REMOTE': '1', 05:22:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:22:51 INFO - 'NO_EM_RESTART': '1', 05:22:51 INFO - 'PAGER': '/bin/cat', 05:22:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:51 INFO - 'PWD': '/builds/slave/test', 05:22:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:22:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:51 INFO - 'SHELL': '/bin/bash', 05:22:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1Yfa5m7KDs/Listeners', 05:22:51 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:22:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:51 INFO - 'USER': 'cltbld', 05:22:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:51 INFO - 'XPC_FLAGS': '0x0', 05:22:51 INFO - 'XPC_SERVICE_NAME': '0', 05:22:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:22:51 INFO - mozversion application_buildid: 20161013030023 05:22:51 INFO - mozversion application_changeset: f03e2740d604d339ed553dad62a3fc54c317f8fa 05:22:51 INFO - mozversion application_display_name: Nightly 05:22:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:22:51 INFO - mozversion application_name: Firefox 05:22:51 INFO - mozversion application_remotingname: firefox 05:22:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:22:51 INFO - mozversion application_vendor: Mozilla 05:22:51 INFO - mozversion application_version: 52.0a1 05:22:51 INFO - mozversion platform_buildid: 20161013030023 05:22:51 INFO - mozversion platform_changeset: f03e2740d604d339ed553dad62a3fc54c317f8fa 05:22:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:22:51 INFO - mozversion platform_version: 52.0a1 05:22:51 INFO - using testdate: 1476361371 05:22:51 INFO - actual date: 1476361371 05:22:51 INFO - starting webserver on 'localhost:49275' 05:22:51 INFO - SUITE-START | Running 2 tests 05:22:51 INFO - TEST-START | dromaeo_css 05:22:51 INFO - operating with platform_type : mac_ 05:22:51 INFO - Initialising browser for dromaeo_css test... 05:22:52 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpUzRkzP/profile http://localhost:49275/getInfo.html) 05:22:54 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 05:22:54 INFO - PROCESS | 1653 | colorDepth:24 05:22:54 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 05:22:54 INFO - PROCESS | 1653 | __metrics 05:22:54 INFO - PROCESS | 1653 | 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" 05:22:54 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:22:55 INFO - TEST-INFO | 1653: exit 0 05:22:55 INFO - Browser initialized. 05:22:55 INFO - Running cycle 1/1 for dromaeo_css test... 05:22:55 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpUzRkzP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:23:07 INFO - PROCESS | 1655 | RSS: Main: 236670976 05:23:07 INFO - PROCESS | 1655 | 05:23:58 INFO - PROCESS | 1655 | 1476361438445 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:26:11 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 05:26:13 INFO - PROCESS | 1655 | RSS: Main: 392650752 05:26:13 INFO - PROCESS | 1655 | 05:29:16 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 05:29:17 INFO - PROCESS | 1655 | RSS: Main: 371478528 05:29:17 INFO - PROCESS | 1655 | 05:32:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 05:32:19 INFO - PROCESS | 1655 | RSS: Main: 339894272 05:32:19 INFO - PROCESS | 1655 | 05:35:22 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 05:35:22 INFO - PROCESS | 1655 | RSS: Main: 354082816 05:35:22 INFO - PROCESS | 1655 | 05:38:26 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 05:38:26 INFO - PROCESS | 1655 | RSS: Main: 334446592 05:38:26 INFO - PROCESS | 1655 | 05:41:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 05:41:30 INFO - PROCESS | 1655 | RSS: Main: 484618240 05:41:30 INFO - PROCESS | 1655 | 05:41:30 INFO - PROCESS | 1655 | __start_tp_report 05:41:30 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 05:41:30 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 05:41:30 INFO - PROCESS | 1655 | |i|pagename|runs| 05:41:30 INFO - PROCESS | 1655 | |0;dojo.html;3257.299680568678;3285.2279714267142;3304.520844477552;3323.4682450807877;3336.683015113565;5359.705216213117;5437.238786569884;5458.69977151257;5575.665460072393;5601.383847776741;5403.945960540394;5475.425080366554;5559.972200139004;5581.804636837712;5587.804426845051;4990.6756060856105;5007.073691367091;5021.6735912165595;5073.137566613685;5074.3657042869645;5072.797088116489;5181.7540232398505;5184.144616138333;5187.896242075175;5216.66091704038;5144.61415393847;5148.6910785353;5164.715940623264;5181.3264275644115;5236.2931004314505;4958.355413796184;5017.37282839645;5023.296738456619;5035.244713292998;5071.061853557104;4491.842785502525;4999.100161970844;5017.971315880236;5054.267131265982;5061.721605311706;5520.585956053272;5545.223668686347;5550.222968784374;5566;5584.162375643646;5814.098814683731;5868.23712917318;5909.113632955048;6106.877862442726;6109.908351374733;6082.8783424331705;6093.025115981422;6122.672617327995;6148.108524264001;6174.25908890936;5649.124385720261;5654.717264136777;5663.433656634304;5664.065429204217;5665.5184309333345;5147.459516750762;6049.758009679564;6136.140940268366;6244.219472565929;6261.373862613702;4617.2612382018715;5870.970645146833;5961.761529538857;5965.3139888912465;6041.637501749821;6159.784407545714;6161.630302181883;6163.3836616338;6163.753449861955;6164.137020817089;6004.849878752979;6064.605800622945;6072.848178795477;6108.816735497942;6119.357467465852;5676.77292908279;5780.988327042751;5784.913226301609;5864.560157988168;5866.911996320058;4761.857144285677;4764.071006153836;4775.2359622460235;4775.785089670973;4776.689515181502;4271.893202669959;4290.721103128287;4312.05134870328;4337.9132417351475;4347.978260108679;5538.113901775697;5610.607257491936;5625.353084395265;5632.915506267409;5667.404922483162;5044.54599086084;5045.520675535819;6009.609375390585;6137.324894261627;6175.166352542349;5446.2375267462585;5516.779328826802;5540.445955404427;5546;5583.581231407661;5285.127953887642;5301.920471192935;5319.973400133052;5335.306410166731;5354.839354819362;6223.377662233831;6275.090111933835;6280.717367718464;6283.183186185766;6295.527835412271;5930.614510056833;6005.309389420273;6171.56799024064;6172.598781079216;6177.536684748572;6222.128901953821;6230.376962303733;6231.22109736283;6240.625562466266;6273.592216505913;6048.818535443944;6060.818175454743;6067.089936509559;6112.724927378367;6117.143599895929;6105.725242364193;6110;6148.44663980244;6151.384861513813;6168.475679567107;5719.942800571941;5803.564732645153;5818.0981947798155;5853.736581853912;5976.163337132892;4906.950930490792;5910.497607703222;5961.314448838439;6006.609570377911;6036.607620504653;5792.82621521355;5793.304803423616;5795.333536643171;5799.768009279582;5814.331351894586;5751.194832723506;5815.883682326415;5836;5952.523798096228;5955.5235581154275;4501.572350626685;5692.829215123553;5766.740496677743;5786.942130578809;5836.503897168788 05:41:30 INFO - PROCESS | 1655 | |1;ext.html;507.505182447114;534.4148157767244;541.3017207801936;559.1333433178572;563.2340017576093;30237.24406889829;30868.845655771718;31612.683873161262;31667.683323166762;31693.049208523724;40645;41927.99999999993;41978;44290.77854610723;44320.55679443204;9423.293253006017;9580.185684216849;9581.856272155923;9583.185429238523;9602.327837051409;9407.247420206368;9519.571619277149;9524.095210954983;9531.80936381272;9536.52317384128;9555.378900371454;9561.856572151388;9561.952190239039;9573.856392154123;9584.37701549397;9447.008064153268;9508.191803696694;9517.5717092731;9540.952295238514;9550.713478595653;9454.85817712735;9507.952460237724;9511.809763804686;9515;9516.476593787338;39203.215935681124;39501.40747888784;39564.80217598922;39585.20829583392;39642.603573964465;3187.5378070179927;3237.352529494087;3472.461768425898;3811.904702382435;3943;29495.492724359065;30401.391972160654;30840.074797756104;30899.382012359853;30944;3079.3995170941675;3159.699828516287;3219.806811591312;3260.9021729348156;3320.9833950830093;476727.61636191944;477219;478337;479157.6042119767;489990;55764.72117639386;56044.43955560474;56071;56100.43899560952;56190.719046404505;58284;58815.70592147012;59116.11325830116;59287.11069333964;59293.703531482926;15825.604359890865;16060.75908861368;16064;16065.759013614805;16077.276522556513;12549.309787961749;12628;12636;12650.30423326726;12656.987441004881;14931.477398291008;15031.473898413504;15052.774208386883;15056.397744090114;15250.694986100216;6894.413974812197;6964;7051.743442098479;7068.257832927571;7082.822929426702;832.8958880139983;923.2291036984193;927.5686805635412;953.3803028031834;1010.5604062232952;28740.850365985545;29011.12966611005;29035.257884526996;29739.702602974125;30035.69964300329;864.5504337744338;1025.2054657640388;1045.6235755128148;1049.8372752223418;1092.8524649172418;14983.951123421255;14993.550193494213;15182.848171518364;15385.923070384577;15434.922825386026;145538.81691774633;168219;170476;172386;173379;46313;46579;56624.433755661914;56792;56836;1215.7933151364289;1250.2186133666278;1308.6793735534854;1348.9865101349058;1369.8630136986221;29199.562006569493;30767.384652307275;30831;30890;31277;47284;47805.76097119492;47900.28149577689;47999.5200048009;48047.03905921932;521434;523635;523885;523973;525298;33253.50119748157;34069.148271293416;38053.61946380611;38264.85205443841;39040;44773.32840007468;44874;45730.76846167722;45750.77124614356;46272;37758;37799.81100094592;38099.23801523899;38704.41943370908;39084.2183156341;37255.441168381956;37721.397797989426;38105.42841857319;38726;39019.804900975316;37417.4387384184;37961.81019094887;38152.80923595475;38226.61773382342;38263.80868095753;32619.836900815342;33513.68602673847;33532.49701254434;34267.314653706286;34367.4844877322;24861.254162375157;24893.502129957662;25120.37199070038;25427.491450171263;25783.355416114748 05:41:30 INFO - PROCESS | 1655 | |2;jquery.html;342.7779964426817;362.6971478815189;362.7841434346563;367.32962343722704;389.46643098954416;14728.263586820669;14771.926140369296;15782.605434864134;18602;20592.69110963333;17082.547983421395;19387.933663648495;19500;19923.302684406048;19941.501462463446;5376.623636345457;5747.109198074305;5754.798582049629;5757.769689212448;5759.5968282220265;5425.810096646618;5607.327120745516;5650.943490565083;5698.316202055759;5710.514606258453;5664.40523745007;5665.093585026397;5669.801556945507;5674.546036317105;5688.4311568843195;5514.558835293167;5573.331200255955;5583.776648934037;5605.607607467479;5642.435756424325;4983.327250821129;4994.026164897847;4995.775190116417;4996.350474438336;4998.475160108209;22644;24555;24580.770961451857;24671;24869.87565062163;2162.664786958024;2166.804987551128;2209.2598979341938;2553.7318581549043;2609.647697560843;14729.705405891822;15329.003614765115;15389.846101538953;15392.384304627802;15967;1774.7426623139702;1807.7559529463485;1861.4601765487992;1867.4771064102074;1924.6631839428185;96837;124392.9999999991;125562;125945.07043661675;126915;10163.695089147337;10274.126699230499;10627.09669678086;10661.253712240225;10885.346879187116;13091.214527128212;13100.017498687446;13113.213207207407;13137.34313284332;13149.60551183466;8058.798530036679;8320.292775114061;8378.371622128365;8463.449875758057;8576.957115214384;8457.112003239694;8485.406021578554;8512.872306915402;8622.913770862337;8697.95651021741;5157.871053223699;5479.95880782658;6287.434130928238;6451.516136289797;6593.307702691244;1546.9480753088008;1823.3253696132515;1836.3113832312883;1848.353076423268;1862.9161687724086;872.7207293665966;890.3322508118911;891.9955400222957;902.2962089570145;992.1764935103846;21097.367078987656;21398.893005534872;21449.034793434334;21508.56982860334;21614;882.9867551986725;920.1028996728137;954.6897258391051;957.674390707163;1026.471367245869;9258.444493330422;11435.542578296774;11463.426828658534;11479.713007174887;11539.653810385702;582818;589379;597096;604141;604523;53780.37152228245;98399;111672;119542;128379;870.6952566601639;885.8582626779685;900.6307413960245;955.8470644696815;985.9211263099078;19235.711464328328;19288.421347359603;19481.51296217606;19493.51266218356;19531.21875124979;50957.72605684888;52694.20958685546;52849.735751322536;54770.72614637061;55465.16802247889;77490;77507;77927;78483.60758196173;79361.60319198367;7351.301626345488;7385.667644955883;7804.9609751950875;7865.488743231672;7866.567338796421;11604.187706860777;12060.396980151132;12837.165584236995;12973.610791676265;13306.933465332611;10358.637447689294;11049.668509944715;11152.386618736047;11215.1027917768;11393;10956.671299861016;10988.450577470934;11115.944420277847;11203.775924481628;11468.254563453349;11042.889571104186;11375.943120284346;11384.886151138382;11437.883991461273;11743.823842642538;7642.159362470013;7745.496542724705;7804.765857024298;7972.84054318922;8002.51984880909;10785.676429707122;11019.449027548751;11090.500927458123;11244.212905096565;11339.319640821577 05:41:30 INFO - PROCESS | 1655 | |3;mootools.html;1514.9015314004585;1533.4939469974909;1727.5422013166512;1858.1545396844438;1885.255324146962;8434.789130271747;8893.466392016484;8906.77733056674;8914.019457859646;8918.91081089189;7804.336631386339;8015.519068855872;8025.157358477363;8454.9854017518;8672.132786721324;2296.0586159674535;2351.905923763047;2352.6706261123454;2353.082297903819;2358.7759162879584;2317.687112239851;2331.778481044298;2342.906283748648;2359.7758212969825;2361.657559653849;2323.082382458928;2340.216027630746;2347.084636991575;2354.3172479980785;2361.551305252005;2264.015153408256;2269.9773002270013;2276.089564174327;2279.886005699708;2281.349815302639;2313.9190128345595;2320.9187678431344;2321.2688003278913;2324.59319619066;2326.5114325991563;8634.481931084092;8715.956420217859;8729.825403491957;8734.781630459225;8747.912520874837;5148.948510514884;5316.308879845634;5316.893662126775;5321.308229930123;5336.706481143536;3407.3185362927265;3496.125968507873;3561.216532362876;3621.3843646580144;3633.382325004729;1068.5405275731432;1069.5133714160038;1075.2903083964625;1092.251807511849;1097.4732128578328;11643.650690479299;11670.533178672842;11721.882781172078;11730.589429369931;11795.764084718343;3821.293060783764;4518.6385089193445;4743;4745.14587374269;4756.738379389167;9912.454814985102;9912.504374781232;9913.80172396548;9925.404475731479;9932.80134397308;5230.5815534757885;5236.581073514109;5240.68555886648;5243.868903277448;5250.7637156328055;4264.488261408651;4296.89257768552;4758.547937945889;4773.7374444405195;4797.952020479751;3843.807809609564;3861.0540417597867;3938.133610605662;3965.8810235692977;3969.3053715599654;1128.949197286124;1162.9068675445055;1242.9937850310691;1247.6606363069245;1256.3718140929536;837.3719710217337;839.6137776622822;895.7984453497911;896.9103089691109;926.3654396738256;5609.326880774252;5688.63023903445;5933.910991335133;5986.610870293417;6028.788992385245;835.1231207232503;927.452802846324;935.5649622925372;954.6467806911487;976.3360914578155;3844.231653439393;3895.1430685249766;3903.765774053566;4010.7393019453643;4029.435878976946;10496.895031049591;10785;10827.296225745302;10843.457827108614;10858.402787846744;10758.623448179276;10784.676459706221;10789.244752867224;10807.78384432307;10850.565977360817;6922.896156557656;7452.254774522505;8035.718749843727;8042.879356809653;8075.071366792777;5886.23478947734;5896.321922978741;5916.881662366814;6048.7278072487725;6112.663803490851;9900.356476828983;9956.800863982824;10055.39667619945;10059.899401005896;10076.798464030824;8895.599698013735;9858.162056225301;10278.074973252444;10405.803332616542;10407.94796026015;6520.347965203442;7154.139923619792;7177;7459.515131516434;7616.80957976055;7928.246816552418;8063.798405039686;8206.917930820615;8228.83542329162;8239.176082391712;6901.206361268519;7291.781246562611;7403.777886663193;7473.17795042534;7556.168821429528;6852.177738671391;7167.928320716935;7303.160136584361;7324.780256592311;7349.154847192642;6896.344847239504;6937.479689023445;7022.612311814008;7044.823879403056;7046.753363632249;4933.038057578881;4983.501649834988;4991.450940396626;5014.423341315795;5070.645054846125;4900.4854490278;4929.06347793918;4971.353724015855;4974.925376119289;5177.145770947752 05:41:30 INFO - PROCESS | 1655 | |4;prototype.html;282.7229315271034;290.925813917451;301.8279580639036;304.44438899009316;305.3191383215428;4182.184474027566;4869.440014398345;5039.950829748;5043.445221025684;5116.692998420097;4256.936145957813;4819.915254237291;4957.454679985199;4963.35476388069;5011.394075080965;3869.8452061917487;3869.8645547405918;3874.903127421823;3881.514810648669;3899.395593682984;3571.7678350907236;3633;3903.4535165076913;3903.6291552302487;3931.862384816532;3246.8863589774364;3486.727344519245;3901.60983901609;3904.3362628353107;3911.3793758181946;3852.5184351956004;3873.6707379872746;3890.801697041177;3897.064704470935;3913.3347330953807;3372.099994005154;3376.2909788944107;3387.983060084684;3398.6431424700545;3403.268297316064;4589.334546490773;4892.559669629751;4895.5838753705975;4931.26031095335;4939.876503087452;4141.7100802943505;4383.216657422559;4393.36296237047;4404.889877753082;4418.359337895987;5398.083680293478;5400.244920400885;5403.567714582824;5405.702686352249;5413.431589683066;3158.9052328430184;3389.9491507627404;3625.0393645683916;3640.672339489432;3695.224002959382;5622.634528755618;5659.773609055633;5660.943390566124;5664.886702265972;5672.858178545569;2987.626546681665;3715.5285948038513;3720.534933133358;3729.366007778684;3732.533433320835;3453.430184019659;3760.6803421708914;3773.396256598931;3785.6025117362833;3793.374093274634;3525.4359302511475;3637.4907512948207;4138.916507202153;4384.912301753947;4390.648748100144;5381;5390.975714614211;5413.482730530322;5416.539594134464;5434.239206511092;1095.5398732532356;1111.972200694973;1207.0584943743893;1292.2956988441322;1296.7536168128215;714.2892821642499;895.1809094678297;911.7036962986923;924.9825192288429;929.3494553812359;4456.5766252205985;4956;5013.418089683816;5040.294358789773;5067.493250674903;4565.657575681837;4773.7374444405195;5181.678671938707;5499.752511136929;5520.282363292827;4458.7324760514475;4819.325294458778;4980.7509624518625;5014.598832093424;5029.471905449948;2370.893309801063;2518.899244030218;2842.9147125586264;2868.4693331733697;2868.985655071753;5345.4654534546235;5708.686022268732;5709.22925405065;5723.484886360247;5732.856678583069;5517.282753242052;5603.2435621191435;5614.9719251403485;5616.1856530803625;5623.8594035149445;5664.923664503731;5681.261436013292;5689.971550142305;5694.636725758846;5709.371969083398;4743.786529606107;5030.346055012825;5394.298741163623;5406.432324605859;5418.593605479684;4689.718616882998;4914.763782479035;5411.917616476641;5427.728613569227;5455.754491047833;5622.887542249214;5655.7454914528125;5659.490645841894;5663.716814159358;5692.886142277049;3434.742394320386;3729.1236559407976;3760.417135344026;3762.4168253920684;3814.961850381461;5553.750081246434;5582.297399293092;5582.650409754115;5583.441655834384;5607.065562381135;5440.156775699773;5453.60932962108;5465.180222966586;5474.698891561003;5480.287562616834;4458.901063760785;4831;5117.029943471715;5424.647397919122;5427.348718153688;4416.536263692266;5053.949460505348;5255.448177941261;5267.367915850123;5288.783579776596;4834.734089625104;4899.363082799213;5169.948300516947;5442.102053161342;5448.455154484678;4640.83757068501;5344.25180474726;5354.973225133849;5373.408925018323;5392.811251606175 05:41:30 INFO - PROCESS | 1655 | |5;yui.html;633.8605506788506;723.52247516639;728.0389885351336;751.7481643649377;773.3581127664039;13959.581212563626;14619.70760584789;15664.921675391606;16406.589835254126;16457.012579245253;14581.854181458182;14787.778183327258;14822.036567623098;14828;14833.110013399204;821.1460081515218;849;851.4550687559966;851.7827953871785;853.0658928473312;678.5860625018735;694.6839188169396;700.7197121151555;813.8413009463158;840.8108175660458;883.5052370672412;893.8569828827389;894.8970868350125;895.9104089591021;896.5875697179273;888.8533391990346;889.0620395482731;889.213046453893;889.2263730554395;891.0287786312886;507.67255120447106;521.8225803226921;637.5537124013163;659.350539718377;660.4815220052236;14167.149971001772;14614.123152610877;14616.70766584673;14628.707425851424;14639.268036598127;13235.338233088307;13437.656234376582;13602.115862469007;13614.863851361557;13916.721665566733;11212;11458.312501249868;11571.710707232302;11630.534778608846;11940.61187762451;3745.700343972476;3748.1379282765115;3864.246471937973;3902.3366027775346;3955.703322250814;110026.44986775095;111587.44206278998;114541.42729286384;114934.85065149325;117036;10885.782284354269;10887;10896.400697961688;10953.178511611502;11193.10455163585;18218.908905455388;21695.457613559473;21950.45123871916;21971.121155153614;22006.229781957554;7682.846343073107;7894.486858354188;7940.602969851484;7960.522368657783;8035.638396272182;3917.7845218512684;4341.544137865541;4383.978080109579;4444.799984000728;4555.886102847389;3389.966100338965;3547.733919956014;3550.952469021645;3555.7510974232073;3557.697595704394;518.245952139636;550.7989583801883;633.338161621107;642.6304874697067;656;613.3069631316583;633.2148136310941;651.7555916531301;661.9470442364599;674.8886433738477;8699.869501957475;9123.543822808966;9193.586288617029;9212.815743685089;9276.304277179239;632.6646877155115;636.7548493829909;657.1982181738272;670.1254862404609;670.6948338505968;3367.124547617637;3502.527158833576;4180.393842892763;4416.602505774432;4420.889477763018;121741;122978;124492;128788;160529;93597;117697.8230217704;120458;121011.39494302472;121184.39407802904;92865;97847.02152978521;104263;106371;110148;9576.616935322509;9627.326087173831;9652.179564737076;9673.951630242085;9678.467684277432;46679.766601166775;61087.38912610817;61943.61127774373;63300;64006.67996659987;87508;89840.5507972456;91442.54278728564;92770.60844087209;94035.52982235045;12174.513019479122;12519.311437871005;12684.682882928;12763.298018609066;13222;14305.356258968579;14765.261736913326;14932.552023439313;15000.324985375466;15453.459128930434;13377;13734.244616546186;13818.861811381757;13962.860371396562;14009.159450433006;13737.728820668686;13739.24434156131;13750.381232844346;14296.642583935485;14652.926735366682;12901.23118196489;13337.399817008452;13572.457101715821;13616;13777.173369597856;10784.460776961278;11029.283096598696;11158.386288754196;11370.919762622538;11437.142214334124;10099.495025248854;10419.94790026045;11388.75468803687;11463.484143213409;11555.942220288845 05:41:30 INFO - PROCESS | 1655 | __end_tp_report 05:41:30 INFO - PROCESS | 1655 | __start_cc_report 05:41:30 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1749 05:41:30 INFO - PROCESS | 1655 | __end_cc_report 05:41:30 INFO - PROCESS | 1655 | __startTimestamp1476362490272__endTimestamp 05:41:30 INFO - PROCESS | 1655 | 05:41:30 INFO - PROCESS | 1655 | ------- Summary: start ------- 05:41:30 INFO - PROCESS | 1655 | Number of tests: 6 05:41:30 INFO - PROCESS | 1655 | 05:41:30 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5558.97 Median:5692.83 stddev:639.72 (11.2%) stddev-sans-first:615.67 05:41:30 INFO - PROCESS | 1655 | Values: 3257.3 3285.2 3304.5 3323.5 3336.7 5359.7 5437.2 5458.7 5575.7 5601.4 5403.9 5475.4 5560.0 5581.8 5587.8 4990.7 5007.1 5021.7 5073.1 5074.4 5072.8 5181.8 5184.1 5187.9 5216.7 5144.6 5148.7 5164.7 5181.3 5236.3 4958.4 5017.4 5023.3 5035.2 5071.1 4491.8 4999.1 5018.0 5054.3 5061.7 5520.6 5545.2 5550.2 5566.0 5584.2 5814.1 5868.2 5909.1 6106.9 6109.9 6082.9 6093.0 6122.7 6148.1 6174.3 5649.1 5654.7 5663.4 5664.1 5665.5 5147.5 6049.8 6136.1 6244.2 6261.4 4617.3 5871.0 5961.8 5965.3 6041.6 6159.8 6161.6 6163.4 6163.8 6164.1 6004.8 6064.6 6072.8 6108.8 6119.4 5676.8 5781.0 5784.9 5864.6 5866.9 4761.9 4764.1 4775.2 4775.8 4776.7 4271.9 4290.7 4312.1 4337.9 4348.0 5538.1 5610.6 5625.4 5632.9 5667.4 5044.5 5045.5 6009.6 6137.3 6175.2 5446.2 5516.8 5540.4 5546.0 5583.6 5285.1 5301.9 5320.0 5335.3 5354.8 6223.4 6275.1 6280.7 6283.2 6295.5 5930.6 6005.3 6171.6 6172.6 6177.5 6222.1 6230.4 6231.2 6240.6 6273.6 6048.8 6060.8 6067.1 6112.7 6117.1 6105.7 6110.0 6148.4 6151.4 6168.5 5719.9 5803.6 5818.1 5853.7 5976.2 4907.0 5910.5 5961.3 6006.6 6036.6 5792.8 5793.3 5795.3 5799.8 5814.3 5751.2 5815.9 5836.0 5952.5 5955.5 4501.6 5692.8 5766.7 5786.9 5836.5 05:41:30 INFO - PROCESS | 1655 | 05:41:30 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:54682.28 Median:38872.90 stddev:112693.34 (289.9%) stddev-sans-first:112936.07 05:41:30 INFO - PROCESS | 1655 | Values: 507.5 534.4 541.3 559.1 563.2 30237.2 30868.8 31612.7 31667.7 31693.0 40645.0 41928.0 41978.0 44290.8 44320.6 9423.3 9580.2 9581.9 9583.2 9602.3 9407.2 9519.6 9524.1 9531.8 9536.5 9555.4 9561.9 9562.0 9573.9 9584.4 9447.0 9508.2 9517.6 9541.0 9550.7 9454.9 9508.0 9511.8 9515.0 9516.5 39203.2 39501.4 39564.8 39585.2 39642.6 3187.5 3237.4 3472.5 3811.9 3943.0 29495.5 30401.4 30840.1 30899.4 30944.0 3079.4 3159.7 3219.8 3260.9 3321.0 476727.6 477219.0 478337.0 479157.6 489990.0 55764.7 56044.4 56071.0 56100.4 56190.7 58284.0 58815.7 59116.1 59287.1 59293.7 15825.6 16060.8 16064.0 16065.8 16077.3 12549.3 12628.0 12636.0 12650.3 12657.0 14931.5 15031.5 15052.8 15056.4 15250.7 6894.4 6964.0 7051.7 7068.3 7082.8 832.9 923.2 927.6 953.4 1010.6 28740.9 29011.1 29035.3 29739.7 30035.7 864.6 1025.2 1045.6 1049.8 1092.9 14984.0 14993.6 15182.8 15385.9 15434.9 145538.8 168219.0 170476.0 172386.0 173379.0 46313.0 46579.0 56624.4 56792.0 56836.0 1215.8 1250.2 1308.7 1349.0 1369.9 29199.6 30767.4 30831.0 30890.0 31277.0 47284.0 47805.8 47900.3 47999.5 48047.0 521434.0 523635.0 523885.0 523973.0 525298.0 33253.5 34069.1 38053.6 38264.9 39040.0 44773.3 44874.0 45730.8 45750.8 46272.0 37758.0 37799.8 38099.2 38704.4 39084.2 37255.4 37721.4 38105.4 38726.0 39019.8 37417.4 37961.8 38152.8 38226.6 38263.8 32619.8 33513.7 33532.5 34267.3 34367.5 24861.3 24893.5 25120.4 25427.5 25783.4 05:41:30 INFO - PROCESS | 1655 | 05:41:30 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:34245.85 Median:2581.69 stddev:99027.03 (3835.7%) stddev-sans-first:99272.11 05:41:30 INFO - PROCESS | 1655 | Values: 342.8 362.7 362.8 367.3 389.5 14728.3 14771.9 15782.6 18602.0 20592.7 17082.5 19387.9 19500.0 19923.3 19941.5 5376.6 5747.1 5754.8 5757.8 5759.6 5425.8 5607.3 5650.9 5698.3 5710.5 5664.4 5665.1 5669.8 5674.5 5688.4 5514.6 5573.3 5583.8 5605.6 5642.4 4983.3 4994.0 4995.8 4996.4 4998.5 22644.0 24555.0 24580.8 24671.0 24869.9 2162.7 2166.8 2209.3 2553.7 2609.6 14729.7 15329.0 15389.8 15392.4 15967.0 1774.7 1807.8 1861.5 1867.5 1924.7 96837.0 124393.0 125562.0 125945.1 126915.0 10163.7 10274.1 10627.1 10661.3 10885.3 13091.2 13100.0 13113.2 13137.3 13149.6 8058.8 8320.3 8378.4 8463.4 8577.0 8457.1 8485.4 8512.9 8622.9 8698.0 5157.9 5480.0 6287.4 6451.5 6593.3 1546.9 1823.3 1836.3 1848.4 1862.9 872.7 890.3 892.0 902.3 992.2 21097.4 21398.9 21449.0 21508.6 21614.0 883.0 920.1 954.7 957.7 1026.5 9258.4 11435.5 11463.4 11479.7 11539.7 582818.0 589379.0 597096.0 604141.0 604523.0 53780.4 98399.0 111672.0 119542.0 128379.0 870.7 885.9 900.6 955.8 985.9 19235.7 19288.4 19481.5 19493.5 19531.2 50957.7 52694.2 52849.7 54770.7 55465.2 77490.0 77507.0 77927.0 78483.6 79361.6 7351.3 7385.7 7805.0 7865.5 7866.6 11604.2 12060.4 12837.2 12973.6 13306.9 10358.6 11049.7 11152.4 11215.1 11393.0 10956.7 10988.5 11115.9 11203.8 11468.3 11042.9 11375.9 11384.9 11437.9 11743.8 7642.2 7745.5 7804.8 7972.8 8002.5 10785.7 11019.4 11090.5 11244.2 11339.3 05:41:30 INFO - PROCESS | 1655 | 05:41:30 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5657.92 Median:4979.21 stddev:3183.32 (63.9%) stddev-sans-first:3177.03 05:41:30 INFO - PROCESS | 1655 | Values: 1514.9 1533.5 1727.5 1858.2 1885.3 8434.8 8893.5 8906.8 8914.0 8918.9 7804.3 8015.5 8025.2 8455.0 8672.1 2296.1 2351.9 2352.7 2353.1 2358.8 2317.7 2331.8 2342.9 2359.8 2361.7 2323.1 2340.2 2347.1 2354.3 2361.6 2264.0 2270.0 2276.1 2279.9 2281.3 2313.9 2320.9 2321.3 2324.6 2326.5 8634.5 8716.0 8729.8 8734.8 8747.9 5148.9 5316.3 5316.9 5321.3 5336.7 3407.3 3496.1 3561.2 3621.4 3633.4 1068.5 1069.5 1075.3 1092.3 1097.5 11643.7 11670.5 11721.9 11730.6 11795.8 3821.3 4518.6 4743.0 4745.1 4756.7 9912.5 9912.5 9913.8 9925.4 9932.8 5230.6 5236.6 5240.7 5243.9 5250.8 4264.5 4296.9 4758.5 4773.7 4798.0 3843.8 3861.1 3938.1 3965.9 3969.3 1128.9 1162.9 1243.0 1247.7 1256.4 837.4 839.6 895.8 896.9 926.4 5609.3 5688.6 5933.9 5986.6 6028.8 835.1 927.5 935.6 954.6 976.3 3844.2 3895.1 3903.8 4010.7 4029.4 10496.9 10785.0 10827.3 10843.5 10858.4 10758.6 10784.7 10789.2 10807.8 10850.6 6922.9 7452.3 8035.7 8042.9 8075.1 5886.2 5896.3 5916.9 6048.7 6112.7 9900.4 9956.8 10055.4 10059.9 10076.8 8895.6 9858.2 10278.1 10405.8 10407.9 6520.3 7154.1 7177.0 7459.5 7616.8 7928.2 8063.8 8206.9 8228.8 8239.2 6901.2 7291.8 7403.8 7473.2 7556.2 6852.2 7167.9 7303.2 7324.8 7349.2 6896.3 6937.5 7022.6 7044.8 7046.8 4933.0 4983.5 4991.5 5014.4 5070.6 4900.5 4929.1 4971.4 4974.9 5177.1 05:41:30 INFO - PROCESS | 1655 | 05:41:30 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4358.26 Median:4923.01 stddev:1359.17 (27.6%) stddev-sans-first:1328.11 05:41:30 INFO - PROCESS | 1655 | Values: 282.7 290.9 301.8 304.4 305.3 4182.2 4869.4 5040.0 5043.4 5116.7 4256.9 4819.9 4957.5 4963.4 5011.4 3869.8 3869.9 3874.9 3881.5 3899.4 3571.8 3633.0 3903.5 3903.6 3931.9 3246.9 3486.7 3901.6 3904.3 3911.4 3852.5 3873.7 3890.8 3897.1 3913.3 3372.1 3376.3 3388.0 3398.6 3403.3 4589.3 4892.6 4895.6 4931.3 4939.9 4141.7 4383.2 4393.4 4404.9 4418.4 5398.1 5400.2 5403.6 5405.7 5413.4 3158.9 3389.9 3625.0 3640.7 3695.2 5622.6 5659.8 5660.9 5664.9 5672.9 2987.6 3715.5 3720.5 3729.4 3732.5 3453.4 3760.7 3773.4 3785.6 3793.4 3525.4 3637.5 4138.9 4384.9 4390.6 5381.0 5391.0 5413.5 5416.5 5434.2 1095.5 1112.0 1207.1 1292.3 1296.8 714.3 895.2 911.7 925.0 929.3 4456.6 4956.0 5013.4 5040.3 5067.5 4565.7 4773.7 5181.7 5499.8 5520.3 4458.7 4819.3 4980.8 5014.6 5029.5 2370.9 2518.9 2842.9 2868.5 2869.0 5345.5 5708.7 5709.2 5723.5 5732.9 5517.3 5603.2 5615.0 5616.2 5623.9 5664.9 5681.3 5690.0 5694.6 5709.4 4743.8 5030.3 5394.3 5406.4 5418.6 4689.7 4914.8 5411.9 5427.7 5455.8 5622.9 5655.7 5659.5 5663.7 5692.9 3434.7 3729.1 3760.4 3762.4 3815.0 5553.8 5582.3 5582.7 5583.4 5607.1 5440.2 5453.6 5465.2 5474.7 5480.3 4458.9 4831.0 5117.0 5424.6 5427.3 4416.5 5053.9 5255.4 5267.4 5288.8 4834.7 4899.4 5169.9 5442.1 5448.5 4640.8 5344.3 5355.0 5373.4 5392.8 05:41:30 INFO - PROCESS | 1655 | 05:41:30 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:23744.30 Median:3549.34 stddev:36962.10 (1041.4%) stddev-sans-first:37025.06 05:41:30 INFO - PROCESS | 1655 | Values: 633.9 723.5 728.0 751.7 773.4 13959.6 14619.7 15664.9 16406.6 16457.0 14581.9 14787.8 14822.0 14828.0 14833.1 821.1 849.0 851.5 851.8 853.1 678.6 694.7 700.7 813.8 840.8 883.5 893.9 894.9 895.9 896.6 888.9 889.1 889.2 889.2 891.0 507.7 521.8 637.6 659.4 660.5 14167.1 14614.1 14616.7 14628.7 14639.3 13235.3 13437.7 13602.1 13614.9 13916.7 11212.0 11458.3 11571.7 11630.5 11940.6 3745.7 3748.1 3864.2 3902.3 3955.7 110026.4 111587.4 114541.4 114934.9 117036.0 10885.8 10887.0 10896.4 10953.2 11193.1 18218.9 21695.5 21950.5 21971.1 22006.2 7682.8 7894.5 7940.6 7960.5 8035.6 3917.8 4341.5 4384.0 4444.8 4555.9 3390.0 3547.7 3551.0 3555.8 3557.7 518.2 550.8 633.3 642.6 656.0 613.3 633.2 651.8 661.9 674.9 8699.9 9123.5 9193.6 9212.8 9276.3 632.7 636.8 657.2 670.1 670.7 3367.1 3502.5 4180.4 4416.6 4420.9 121741.0 122978.0 124492.0 128788.0 160529.0 93597.0 117697.8 120458.0 121011.4 121184.4 92865.0 97847.0 104263.0 106371.0 110148.0 9576.6 9627.3 9652.2 9674.0 9678.5 46679.8 61087.4 61943.6 63300.0 64006.7 87508.0 89840.6 91442.5 92770.6 94035.5 12174.5 12519.3 12684.7 12763.3 13222.0 14305.4 14765.3 14932.6 15000.3 15453.5 13377.0 13734.2 13818.9 13962.9 14009.2 13737.7 13739.2 13750.4 14296.6 14652.9 12901.2 13337.4 13572.5 13616.0 13777.2 10784.5 11029.3 11158.4 11370.9 11437.1 10099.5 10419.9 11388.8 11463.5 11555.9 05:41:30 INFO - PROCESS | 1655 | -------- Summary: end -------- 05:41:30 INFO - PROCESS | 1655 | 05:41:30 INFO - TEST-INFO | 1655: exit 0 05:41:30 INFO - TEST-OK | dromaeo_css | took 1118878ms 05:41:30 INFO - TEST-START | kraken 05:41:30 INFO - operating with platform_type : mac_ 05:41:30 INFO - Initialising browser for kraken test... 05:41:30 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpDTkVzD/profile http://localhost:49275/getInfo.html) 05:41:33 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 05:41:33 INFO - PROCESS | 1662 | colorDepth:24 05:41:33 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 05:41:33 INFO - PROCESS | 1662 | __metrics 05:41:33 INFO - PROCESS | 1662 | 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" 05:41:33 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:41:34 INFO - TEST-INFO | 1662: exit 0 05:41:34 INFO - Browser initialized. 05:41:34 INFO - Running cycle 1/1 for kraken test... 05:41:34 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpDTkVzD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:46 INFO - PROCESS | 1663 | RSS: Main: 237133824 05:41:46 INFO - PROCESS | 1663 | 05:41:48 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 05:41:49 INFO - PROCESS | 1663 | RSS: Main: 295710720 05:41:49 INFO - PROCESS | 1663 | 05:41:52 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 05:41:53 INFO - PROCESS | 1663 | RSS: Main: 297394176 05:41:53 INFO - PROCESS | 1663 | 05:41:56 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 05:41:57 INFO - PROCESS | 1663 | RSS: Main: 388333568 05:41:57 INFO - PROCESS | 1663 | 05:41:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 05:41:59 INFO - PROCESS | 1663 | RSS: Main: 290508800 05:41:59 INFO - PROCESS | 1663 | 05:42:01 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:42:02 INFO - PROCESS | 1663 | RSS: Main: 293273600 05:42:02 INFO - PROCESS | 1663 | 05:42:05 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 05:42:05 INFO - PROCESS | 1663 | RSS: Main: 330563584 05:42:05 INFO - PROCESS | 1663 | 05:42:08 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 05:42:09 INFO - PROCESS | 1663 | RSS: Main: 332517376 05:42:09 INFO - PROCESS | 1663 | 05:42:12 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 05:42:12 INFO - PROCESS | 1663 | RSS: Main: 329859072 05:42:12 INFO - PROCESS | 1663 | 05:42:14 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:42:14 INFO - PROCESS | 1663 | RSS: Main: 303570944 05:42:14 INFO - PROCESS | 1663 | 05:42:16 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:42:17 INFO - PROCESS | 1663 | RSS: Main: 308064256 05:42:17 INFO - PROCESS | 1663 | 05:42:19 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:42:20 INFO - PROCESS | 1663 | RSS: Main: 313540608 05:42:20 INFO - PROCESS | 1663 | 05:42:23 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:42:24 INFO - PROCESS | 1663 | RSS: Main: 322035712 05:42:24 INFO - PROCESS | 1663 | 05:42:27 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:42:27 INFO - PROCESS | 1663 | RSS: Main: 321675264 05:42:27 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 05:42:29 INFO - PROCESS | 1663 | RSS: Main: 365961216 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | __start_tp_report 05:42:29 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 05:42:29 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 05:42:29 INFO - PROCESS | 1663 | |i|pagename|runs| 05:42:29 INFO - PROCESS | 1663 | |0;ai-astar;114;113;113;112;113;113;112;112;113;114 05:42:29 INFO - PROCESS | 1663 | |1;audio-beat-detection;153;144;142;153;115;114;115;115;146;143 05:42:29 INFO - PROCESS | 1663 | |2;audio-dft;184;184;185;181;184;183;183;181;182;185 05:42:29 INFO - PROCESS | 1663 | |3;audio-fft;81;59;65;115;108;83;57;57;84;58 05:42:29 INFO - PROCESS | 1663 | |4;audio-oscillator;82;71;95;71;69;68;90;65;68;91 05:42:29 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;91;88;91;88;90;88;92;89;90;88 05:42:29 INFO - PROCESS | 1663 | |6;imaging-darkroom;98;96;98;98;98;99;99;98;98;98 05:42:29 INFO - PROCESS | 1663 | |7;imaging-desaturate;84;85;84;85;100;130;84;84;86;83 05:42:29 INFO - PROCESS | 1663 | |8;json-parse-financial;57;54;54;55;55;58;55;55;56;55 05:42:29 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;64;71;70;75;75;71;69;72;70;71 05:42:29 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;87;86;90;89;95;107;108;87;90;89 05:42:29 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;147;146;161;140;140;145;144;145;230;145 05:42:29 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;172;173;189;236;183;173;172;171;169;171 05:42:29 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;70;69;66;68;69;69;67;68;68;68 05:42:29 INFO - PROCESS | 1663 | __end_tp_report 05:42:29 INFO - PROCESS | 1663 | __start_cc_report 05:42:29 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2064 05:42:29 INFO - PROCESS | 1663 | __end_cc_report 05:42:29 INFO - PROCESS | 1663 | __startTimestamp1476362549706__endTimestamp 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | ------- Summary: start ------- 05:42:29 INFO - PROCESS | 1663 | Number of tests: 14 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:112.90 Median:113.00 stddev:0.74 (0.7%) stddev-sans-first:0.67 05:42:29 INFO - PROCESS | 1663 | Values: 114.0 113.0 113.0 112.0 113.0 113.0 112.0 112.0 113.0 114.0 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:134.00 Median:143.50 stddev:16.98 (11.8%) stddev-sans-first:16.56 05:42:29 INFO - PROCESS | 1663 | Values: 153.0 144.0 142.0 153.0 115.0 114.0 115.0 115.0 146.0 143.0 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:1.48 (0.8%) stddev-sans-first:1.54 05:42:29 INFO - PROCESS | 1663 | Values: 184.0 184.0 185.0 181.0 184.0 183.0 183.0 181.0 182.0 185.0 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:76.70 Median:62.00 stddev:21.43 (34.6%) stddev-sans-first:22.68 05:42:29 INFO - PROCESS | 1663 | Values: 81.0 59.0 65.0 115.0 108.0 83.0 57.0 57.0 84.0 58.0 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:76.50 stddev:11.33 (14.8%) stddev-sans-first:11.88 05:42:29 INFO - PROCESS | 1663 | Values: 82.0 71.0 95.0 71.0 69.0 68.0 90.0 65.0 68.0 91.0 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:89.50 Median:90.00 stddev:1.51 (1.7%) stddev-sans-first:1.50 05:42:29 INFO - PROCESS | 1663 | Values: 91.0 88.0 91.0 88.0 90.0 88.0 92.0 89.0 90.0 88.0 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:98.00 Median:98.00 stddev:0.82 (0.8%) stddev-sans-first:0.87 05:42:29 INFO - PROCESS | 1663 | Values: 98.0 96.0 98.0 98.0 98.0 99.0 99.0 98.0 98.0 98.0 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:90.50 Median:84.00 stddev:14.74 (17.6%) stddev-sans-first:15.45 05:42:29 INFO - PROCESS | 1663 | Values: 84.0 85.0 84.0 85.0 100.0 130.0 84.0 84.0 86.0 83.0 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.00 stddev:1.26 (2.3%) stddev-sans-first:1.20 05:42:29 INFO - PROCESS | 1663 | Values: 57.0 54.0 54.0 55.0 55.0 58.0 55.0 55.0 56.0 55.0 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:3.12 (4.4%) stddev-sans-first:2.13 05:42:29 INFO - PROCESS | 1663 | Values: 64.0 71.0 70.0 75.0 75.0 71.0 69.0 72.0 70.0 71.0 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:92.80 Median:89.00 stddev:8.13 (9.1%) stddev-sans-first:8.35 05:42:29 INFO - PROCESS | 1663 | Values: 87.0 86.0 90.0 89.0 95.0 107.0 108.0 87.0 90.0 89.0 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:154.30 Median:145.50 stddev:27.23 (18.7%) stddev-sans-first:28.75 05:42:29 INFO - PROCESS | 1663 | Values: 147.0 146.0 161.0 140.0 140.0 145.0 144.0 145.0 230.0 145.0 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.90 Median:173.00 stddev:20.35 (11.8%) stddev-sans-first:21.33 05:42:29 INFO - PROCESS | 1663 | Values: 172.0 173.0 189.0 236.0 183.0 173.0 172.0 171.0 169.0 171.0 05:42:29 INFO - PROCESS | 1663 | 05:42:29 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.50 stddev:1.14 (1.7%) stddev-sans-first:1.00 05:42:29 INFO - PROCESS | 1663 | Values: 70.0 69.0 66.0 68.0 69.0 69.0 67.0 68.0 68.0 68.0 05:42:29 INFO - PROCESS | 1663 | -------- Summary: end -------- 05:42:29 INFO - PROCESS | 1663 | 05:42:30 INFO - TEST-INFO | 1663: exit 0 05:42:30 INFO - TEST-OK | kraken | took 59756ms 05:42:30 INFO - SUITE-END | took 1178s 05:42:30 INFO - Completed test suite (00:19:38) 05:42:30 INFO - javascript benchmark 05:42:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3257.299680568678, 3285.2279714267142, 3304.520844477552, 3323.4682450807877, 3336.683015113565, 5359.705216213117, 5437.238786569884, 5458.69977151257, 5575.665460072393, 5601.383847776741, 5403.945960540394, 5475.425080366554, 5559.972200139004, 5581.804636837712, 5587.804426845051, 4990.6756060856105, 5007.073691367091, 5021.6735912165595, 5073.137566613685, 5074.3657042869645, 5072.797088116489, 5181.7540232398505, 5184.144616138333, 5187.896242075175, 5216.66091704038, 5144.61415393847, 5148.6910785353, 5164.715940623264, 5181.3264275644115, 5236.2931004314505, 4958.355413796184, 5017.37282839645, 5023.296738456619, 5035.244713292998, 5071.061853557104, 4491.842785502525, 4999.100161970844, 5017.971315880236, 5054.267131265982, 5061.721605311706, 5520.585956053272, 5545.223668686347, 5550.222968784374, 5566.0, 5584.162375643646, 5814.098814683731, 5868.23712917318, 5909.113632955048, 6106.877862442726, 6109.908351374733, 6082.8783424331705, 6093.025115981422, 6122.672617327995, 6148.108524264001, 6174.25908890936, 5649.124385720261, 5654.717264136777, 5663.433656634304, 5664.065429204217, 5665.5184309333345, 5147.459516750762, 6049.758009679564, 6136.140940268366, 6244.219472565929, 6261.373862613702, 4617.2612382018715, 5870.970645146833, 5961.761529538857, 5965.3139888912465, 6041.637501749821, 6159.784407545714, 6161.630302181883, 6163.3836616338, 6163.753449861955, 6164.137020817089, 6004.849878752979, 6064.605800622945, 6072.848178795477, 6108.816735497942, 6119.357467465852, 5676.77292908279, 5780.988327042751, 5784.913226301609, 5864.560157988168, 5866.911996320058, 4761.857144285677, 4764.071006153836, 4775.2359622460235, 4775.785089670973, 4776.689515181502, 4271.893202669959, 4290.721103128287, 4312.05134870328, 4337.9132417351475, 4347.978260108679, 5538.113901775697, 5610.607257491936, 5625.353084395265, 5632.915506267409, 5667.404922483162, 5044.54599086084, 5045.520675535819, 6009.609375390585, 6137.324894261627, 6175.166352542349, 5446.2375267462585, 5516.779328826802, 5540.445955404427, 5546.0, 5583.581231407661, 5285.127953887642, 5301.920471192935, 5319.973400133052, 5335.306410166731, 5354.839354819362, 6223.377662233831, 6275.090111933835, 6280.717367718464, 6283.183186185766, 6295.527835412271, 5930.614510056833, 6005.309389420273, 6171.56799024064, 6172.598781079216, 6177.536684748572, 6222.128901953821, 6230.376962303733, 6231.22109736283, 6240.625562466266, 6273.592216505913, 6048.818535443944, 6060.818175454743, 6067.089936509559, 6112.724927378367, 6117.143599895929, 6105.725242364193, 6110.0, 6148.44663980244, 6151.384861513813, 6168.475679567107, 5719.942800571941, 5803.564732645153, 5818.0981947798155, 5853.736581853912, 5976.163337132892, 4906.950930490792, 5910.497607703222, 5961.314448838439, 6006.609570377911, 6036.607620504653, 5792.82621521355, 5793.304803423616, 5795.333536643171, 5799.768009279582, 5814.331351894586, 5751.194832723506, 5815.883682326415, 5836.0, 5952.523798096228, 5955.5235581154275, 4501.572350626685, 5692.829215123553, 5766.740496677743, 5786.942130578809, 5836.503897168788], "value": 5520.020141505138, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.505182447114, 534.4148157767244, 541.3017207801936, 559.1333433178572, 563.2340017576093, 30237.24406889829, 30868.845655771718, 31612.683873161262, 31667.683323166762, 31693.049208523724, 40645.0, 41927.99999999993, 41978.0, 44290.77854610723, 44320.55679443204, 9423.293253006017, 9580.185684216849, 9581.856272155923, 9583.185429238523, 9602.327837051409, 9407.247420206368, 9519.571619277149, 9524.095210954983, 9531.80936381272, 9536.52317384128, 9555.378900371454, 9561.856572151388, 9561.952190239039, 9573.856392154123, 9584.37701549397, 9447.008064153268, 9508.191803696694, 9517.5717092731, 9540.952295238514, 9550.713478595653, 9454.85817712735, 9507.952460237724, 9511.809763804686, 9515.0, 9516.476593787338, 39203.215935681124, 39501.40747888784, 39564.80217598922, 39585.20829583392, 39642.603573964465, 3187.5378070179927, 3237.352529494087, 3472.461768425898, 3811.904702382435, 3943.0, 29495.492724359065, 30401.391972160654, 30840.074797756104, 30899.382012359853, 30944.0, 3079.3995170941675, 3159.699828516287, 3219.806811591312, 3260.9021729348156, 3320.9833950830093, 476727.61636191944, 477219.0, 478337.0, 479157.6042119767, 489990.0, 55764.72117639386, 56044.43955560474, 56071.0, 56100.43899560952, 56190.719046404505, 58284.0, 58815.70592147012, 59116.11325830116, 59287.11069333964, 59293.703531482926, 15825.604359890865, 16060.75908861368, 16064.0, 16065.759013614805, 16077.276522556513, 12549.309787961749, 12628.0, 12636.0, 12650.30423326726, 12656.987441004881, 14931.477398291008, 15031.473898413504, 15052.774208386883, 15056.397744090114, 15250.694986100216, 6894.413974812197, 6964.0, 7051.743442098479, 7068.257832927571, 7082.822929426702, 832.8958880139983, 923.2291036984193, 927.5686805635412, 953.3803028031834, 1010.5604062232952, 28740.850365985545, 29011.12966611005, 29035.257884526996, 29739.702602974125, 30035.69964300329, 864.5504337744338, 1025.2054657640388, 1045.6235755128148, 1049.8372752223418, 1092.8524649172418, 14983.951123421255, 14993.550193494213, 15182.848171518364, 15385.923070384577, 15434.922825386026, 145538.81691774633, 168219.0, 170476.0, 172386.0, 173379.0, 46313.0, 46579.0, 56624.433755661914, 56792.0, 56836.0, 1215.7933151364289, 1250.2186133666278, 1308.6793735534854, 1348.9865101349058, 1369.8630136986221, 29199.562006569493, 30767.384652307275, 30831.0, 30890.0, 31277.0, 47284.0, 47805.76097119492, 47900.28149577689, 47999.5200048009, 48047.03905921932, 521434.0, 523635.0, 523885.0, 523973.0, 525298.0, 33253.50119748157, 34069.148271293416, 38053.61946380611, 38264.85205443841, 39040.0, 44773.32840007468, 44874.0, 45730.76846167722, 45750.77124614356, 46272.0, 37758.0, 37799.81100094592, 38099.23801523899, 38704.41943370908, 39084.2183156341, 37255.441168381956, 37721.397797989426, 38105.42841857319, 38726.0, 39019.804900975316, 37417.4387384184, 37961.81019094887, 38152.80923595475, 38226.61773382342, 38263.80868095753, 32619.836900815342, 33513.68602673847, 33532.49701254434, 34267.314653706286, 34367.4844877322, 24861.254162375157, 24893.502129957662, 25120.37199070038, 25427.491450171263, 25783.355416114748], "value": 18808.234361696985, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.7779964426817, 362.6971478815189, 362.7841434346563, 367.32962343722704, 389.46643098954416, 14728.263586820669, 14771.926140369296, 15782.605434864134, 18602.0, 20592.69110963333, 17082.547983421395, 19387.933663648495, 19500.0, 19923.302684406048, 19941.501462463446, 5376.623636345457, 5747.109198074305, 5754.798582049629, 5757.769689212448, 5759.5968282220265, 5425.810096646618, 5607.327120745516, 5650.943490565083, 5698.316202055759, 5710.514606258453, 5664.40523745007, 5665.093585026397, 5669.801556945507, 5674.546036317105, 5688.4311568843195, 5514.558835293167, 5573.331200255955, 5583.776648934037, 5605.607607467479, 5642.435756424325, 4983.327250821129, 4994.026164897847, 4995.775190116417, 4996.350474438336, 4998.475160108209, 22644.0, 24555.0, 24580.770961451857, 24671.0, 24869.87565062163, 2162.664786958024, 2166.804987551128, 2209.2598979341938, 2553.7318581549043, 2609.647697560843, 14729.705405891822, 15329.003614765115, 15389.846101538953, 15392.384304627802, 15967.0, 1774.7426623139702, 1807.7559529463485, 1861.4601765487992, 1867.4771064102074, 1924.6631839428185, 96837.0, 124392.9999999991, 125562.0, 125945.07043661675, 126915.0, 10163.695089147337, 10274.126699230499, 10627.09669678086, 10661.253712240225, 10885.346879187116, 13091.214527128212, 13100.017498687446, 13113.213207207407, 13137.34313284332, 13149.60551183466, 8058.798530036679, 8320.292775114061, 8378.371622128365, 8463.449875758057, 8576.957115214384, 8457.112003239694, 8485.406021578554, 8512.872306915402, 8622.913770862337, 8697.95651021741, 5157.871053223699, 5479.95880782658, 6287.434130928238, 6451.516136289797, 6593.307702691244, 1546.9480753088008, 1823.3253696132515, 1836.3113832312883, 1848.353076423268, 1862.9161687724086, 872.7207293665966, 890.3322508118911, 891.9955400222957, 902.2962089570145, 992.1764935103846, 21097.367078987656, 21398.893005534872, 21449.034793434334, 21508.56982860334, 21614.0, 882.9867551986725, 920.1028996728137, 954.6897258391051, 957.674390707163, 1026.471367245869, 9258.444493330422, 11435.542578296774, 11463.426828658534, 11479.713007174887, 11539.653810385702, 582818.0, 589379.0, 597096.0, 604141.0, 604523.0, 53780.37152228245, 98399.0, 111672.0, 119542.0, 128379.0, 870.6952566601639, 885.8582626779685, 900.6307413960245, 955.8470644696815, 985.9211263099078, 19235.711464328328, 19288.421347359603, 19481.51296217606, 19493.51266218356, 19531.21875124979, 50957.72605684888, 52694.20958685546, 52849.735751322536, 54770.72614637061, 55465.16802247889, 77490.0, 77507.0, 77927.0, 78483.60758196173, 79361.60319198367, 7351.301626345488, 7385.667644955883, 7804.9609751950875, 7865.488743231672, 7866.567338796421, 11604.187706860777, 12060.396980151132, 12837.165584236995, 12973.610791676265, 13306.933465332611, 10358.637447689294, 11049.668509944715, 11152.386618736047, 11215.1027917768, 11393.0, 10956.671299861016, 10988.450577470934, 11115.944420277847, 11203.775924481628, 11468.254563453349, 11042.889571104186, 11375.943120284346, 11384.886151138382, 11437.883991461273, 11743.823842642538, 7642.159362470013, 7745.496542724705, 7804.765857024298, 7972.84054318922, 8002.51984880909, 10785.676429707122, 11019.449027548751, 11090.500927458123, 11244.212905096565, 11339.319640821577], "value": 9454.361306488854, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1514.9015314004585, 1533.4939469974909, 1727.5422013166512, 1858.1545396844438, 1885.255324146962, 8434.789130271747, 8893.466392016484, 8906.77733056674, 8914.019457859646, 8918.91081089189, 7804.336631386339, 8015.519068855872, 8025.157358477363, 8454.9854017518, 8672.132786721324, 2296.0586159674535, 2351.905923763047, 2352.6706261123454, 2353.082297903819, 2358.7759162879584, 2317.687112239851, 2331.778481044298, 2342.906283748648, 2359.7758212969825, 2361.657559653849, 2323.082382458928, 2340.216027630746, 2347.084636991575, 2354.3172479980785, 2361.551305252005, 2264.015153408256, 2269.9773002270013, 2276.089564174327, 2279.886005699708, 2281.349815302639, 2313.9190128345595, 2320.9187678431344, 2321.2688003278913, 2324.59319619066, 2326.5114325991563, 8634.481931084092, 8715.956420217859, 8729.825403491957, 8734.781630459225, 8747.912520874837, 5148.948510514884, 5316.308879845634, 5316.893662126775, 5321.308229930123, 5336.706481143536, 3407.3185362927265, 3496.125968507873, 3561.216532362876, 3621.3843646580144, 3633.382325004729, 1068.5405275731432, 1069.5133714160038, 1075.2903083964625, 1092.251807511849, 1097.4732128578328, 11643.650690479299, 11670.533178672842, 11721.882781172078, 11730.589429369931, 11795.764084718343, 3821.293060783764, 4518.6385089193445, 4743.0, 4745.14587374269, 4756.738379389167, 9912.454814985102, 9912.504374781232, 9913.80172396548, 9925.404475731479, 9932.80134397308, 5230.5815534757885, 5236.581073514109, 5240.68555886648, 5243.868903277448, 5250.7637156328055, 4264.488261408651, 4296.89257768552, 4758.547937945889, 4773.7374444405195, 4797.952020479751, 3843.807809609564, 3861.0540417597867, 3938.133610605662, 3965.8810235692977, 3969.3053715599654, 1128.949197286124, 1162.9068675445055, 1242.9937850310691, 1247.6606363069245, 1256.3718140929536, 837.3719710217337, 839.6137776622822, 895.7984453497911, 896.9103089691109, 926.3654396738256, 5609.326880774252, 5688.63023903445, 5933.910991335133, 5986.610870293417, 6028.788992385245, 835.1231207232503, 927.452802846324, 935.5649622925372, 954.6467806911487, 976.3360914578155, 3844.231653439393, 3895.1430685249766, 3903.765774053566, 4010.7393019453643, 4029.435878976946, 10496.895031049591, 10785.0, 10827.296225745302, 10843.457827108614, 10858.402787846744, 10758.623448179276, 10784.676459706221, 10789.244752867224, 10807.78384432307, 10850.565977360817, 6922.896156557656, 7452.254774522505, 8035.718749843727, 8042.879356809653, 8075.071366792777, 5886.23478947734, 5896.321922978741, 5916.881662366814, 6048.7278072487725, 6112.663803490851, 9900.356476828983, 9956.800863982824, 10055.39667619945, 10059.899401005896, 10076.798464030824, 8895.599698013735, 9858.162056225301, 10278.074973252444, 10405.803332616542, 10407.94796026015, 6520.347965203442, 7154.139923619792, 7177.0, 7459.515131516434, 7616.80957976055, 7928.246816552418, 8063.798405039686, 8206.917930820615, 8228.83542329162, 8239.176082391712, 6901.206361268519, 7291.781246562611, 7403.777886663193, 7473.17795042534, 7556.168821429528, 6852.177738671391, 7167.928320716935, 7303.160136584361, 7324.780256592311, 7349.154847192642, 6896.344847239504, 6937.479689023445, 7022.612311814008, 7044.823879403056, 7046.753363632249, 4933.038057578881, 4983.501649834988, 4991.450940396626, 5014.423341315795, 5070.645054846125, 4900.4854490278, 4929.06347793918, 4971.353724015855, 4974.925376119289, 5177.145770947752], "value": 4546.228712210766, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.7229315271034, 290.925813917451, 301.8279580639036, 304.44438899009316, 305.3191383215428, 4182.184474027566, 4869.440014398345, 5039.950829748, 5043.445221025684, 5116.692998420097, 4256.936145957813, 4819.915254237291, 4957.454679985199, 4963.35476388069, 5011.394075080965, 3869.8452061917487, 3869.8645547405918, 3874.903127421823, 3881.514810648669, 3899.395593682984, 3571.7678350907236, 3633.0, 3903.4535165076913, 3903.6291552302487, 3931.862384816532, 3246.8863589774364, 3486.727344519245, 3901.60983901609, 3904.3362628353107, 3911.3793758181946, 3852.5184351956004, 3873.6707379872746, 3890.801697041177, 3897.064704470935, 3913.3347330953807, 3372.099994005154, 3376.2909788944107, 3387.983060084684, 3398.6431424700545, 3403.268297316064, 4589.334546490773, 4892.559669629751, 4895.5838753705975, 4931.26031095335, 4939.876503087452, 4141.7100802943505, 4383.216657422559, 4393.36296237047, 4404.889877753082, 4418.359337895987, 5398.083680293478, 5400.244920400885, 5403.567714582824, 5405.702686352249, 5413.431589683066, 3158.9052328430184, 3389.9491507627404, 3625.0393645683916, 3640.672339489432, 3695.224002959382, 5622.634528755618, 5659.773609055633, 5660.943390566124, 5664.886702265972, 5672.858178545569, 2987.626546681665, 3715.5285948038513, 3720.534933133358, 3729.366007778684, 3732.533433320835, 3453.430184019659, 3760.6803421708914, 3773.396256598931, 3785.6025117362833, 3793.374093274634, 3525.4359302511475, 3637.4907512948207, 4138.916507202153, 4384.912301753947, 4390.648748100144, 5381.0, 5390.975714614211, 5413.482730530322, 5416.539594134464, 5434.239206511092, 1095.5398732532356, 1111.972200694973, 1207.0584943743893, 1292.2956988441322, 1296.7536168128215, 714.2892821642499, 895.1809094678297, 911.7036962986923, 924.9825192288429, 929.3494553812359, 4456.5766252205985, 4956.0, 5013.418089683816, 5040.294358789773, 5067.493250674903, 4565.657575681837, 4773.7374444405195, 5181.678671938707, 5499.752511136929, 5520.282363292827, 4458.7324760514475, 4819.325294458778, 4980.7509624518625, 5014.598832093424, 5029.471905449948, 2370.893309801063, 2518.899244030218, 2842.9147125586264, 2868.4693331733697, 2868.985655071753, 5345.4654534546235, 5708.686022268732, 5709.22925405065, 5723.484886360247, 5732.856678583069, 5517.282753242052, 5603.2435621191435, 5614.9719251403485, 5616.1856530803625, 5623.8594035149445, 5664.923664503731, 5681.261436013292, 5689.971550142305, 5694.636725758846, 5709.371969083398, 4743.786529606107, 5030.346055012825, 5394.298741163623, 5406.432324605859, 5418.593605479684, 4689.718616882998, 4914.763782479035, 5411.917616476641, 5427.728613569227, 5455.754491047833, 5622.887542249214, 5655.7454914528125, 5659.490645841894, 5663.716814159358, 5692.886142277049, 3434.742394320386, 3729.1236559407976, 3760.417135344026, 3762.4168253920684, 3814.961850381461, 5553.750081246434, 5582.297399293092, 5582.650409754115, 5583.441655834384, 5607.065562381135, 5440.156775699773, 5453.60932962108, 5465.180222966586, 5474.698891561003, 5480.287562616834, 4458.901063760785, 4831.0, 5117.029943471715, 5424.647397919122, 5427.348718153688, 4416.536263692266, 5053.949460505348, 5255.448177941261, 5267.367915850123, 5288.783579776596, 4834.734089625104, 4899.363082799213, 5169.948300516947, 5442.102053161342, 5448.455154484678, 4640.83757068501, 5344.25180474726, 5354.973225133849, 5373.408925018323, 5392.811251606175], "value": 3923.8797060850716, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [633.8605506788506, 723.52247516639, 728.0389885351336, 751.7481643649377, 773.3581127664039, 13959.581212563626, 14619.70760584789, 15664.921675391606, 16406.589835254126, 16457.012579245253, 14581.854181458182, 14787.778183327258, 14822.036567623098, 14828.0, 14833.110013399204, 821.1460081515218, 849.0, 851.4550687559966, 851.7827953871785, 853.0658928473312, 678.5860625018735, 694.6839188169396, 700.7197121151555, 813.8413009463158, 840.8108175660458, 883.5052370672412, 893.8569828827389, 894.8970868350125, 895.9104089591021, 896.5875697179273, 888.8533391990346, 889.0620395482731, 889.213046453893, 889.2263730554395, 891.0287786312886, 507.67255120447106, 521.8225803226921, 637.5537124013163, 659.350539718377, 660.4815220052236, 14167.149971001772, 14614.123152610877, 14616.70766584673, 14628.707425851424, 14639.268036598127, 13235.338233088307, 13437.656234376582, 13602.115862469007, 13614.863851361557, 13916.721665566733, 11212.0, 11458.312501249868, 11571.710707232302, 11630.534778608846, 11940.61187762451, 3745.700343972476, 3748.1379282765115, 3864.246471937973, 3902.3366027775346, 3955.703322250814, 110026.44986775095, 111587.44206278998, 114541.42729286384, 114934.85065149325, 117036.0, 10885.782284354269, 10887.0, 10896.400697961688, 10953.178511611502, 11193.10455163585, 18218.908905455388, 21695.457613559473, 21950.45123871916, 21971.121155153614, 22006.229781957554, 7682.846343073107, 7894.486858354188, 7940.602969851484, 7960.522368657783, 8035.638396272182, 3917.7845218512684, 4341.544137865541, 4383.978080109579, 4444.799984000728, 4555.886102847389, 3389.966100338965, 3547.733919956014, 3550.952469021645, 3555.7510974232073, 3557.697595704394, 518.245952139636, 550.7989583801883, 633.338161621107, 642.6304874697067, 656.0, 613.3069631316583, 633.2148136310941, 651.7555916531301, 661.9470442364599, 674.8886433738477, 8699.869501957475, 9123.543822808966, 9193.586288617029, 9212.815743685089, 9276.304277179239, 632.6646877155115, 636.7548493829909, 657.1982181738272, 670.1254862404609, 670.6948338505968, 3367.124547617637, 3502.527158833576, 4180.393842892763, 4416.602505774432, 4420.889477763018, 121741.0, 122978.0, 124492.0, 128788.0, 160529.0, 93597.0, 117697.8230217704, 120458.0, 121011.39494302472, 121184.39407802904, 92865.0, 97847.02152978521, 104263.0, 106371.0, 110148.0, 9576.616935322509, 9627.326087173831, 9652.179564737076, 9673.951630242085, 9678.467684277432, 46679.766601166775, 61087.38912610817, 61943.61127774373, 63300.0, 64006.67996659987, 87508.0, 89840.5507972456, 91442.54278728564, 92770.60844087209, 94035.52982235045, 12174.513019479122, 12519.311437871005, 12684.682882928, 12763.298018609066, 13222.0, 14305.356258968579, 14765.261736913326, 14932.552023439313, 15000.324985375466, 15453.459128930434, 13377.0, 13734.244616546186, 13818.861811381757, 13962.860371396562, 14009.159450433006, 13737.728820668686, 13739.24434156131, 13750.381232844346, 14296.642583935485, 14652.926735366682, 12901.23118196489, 13337.399817008452, 13572.457101715821, 13616.0, 13777.173369597856, 10784.460776961278, 11029.283096598696, 11158.386288754196, 11370.919762622538, 11437.142214334124, 10099.495025248854, 10419.94790026045, 11388.75468803687, 11463.484143213409, 11555.942220288845], "value": 7639.253720997948, "unit": "score"}], "name": "dromaeo_css", "value": 7151.50633846363, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 112.0, 113.0, 113.0, 112.0, 112.0, 113.0, 114.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 144.0, 142.0, 153.0, 115.0, 114.0, 115.0, 115.0, 146.0, 143.0], "value": 134.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 185.0, 181.0, 184.0, 183.0, 183.0, 181.0, 182.0, 185.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 65.0, 115.0, 108.0, 83.0, 57.0, 57.0, 84.0, 58.0], "value": 76.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 95.0, 71.0, 69.0, 68.0, 90.0, 65.0, 68.0, 91.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 91.0, 88.0, 90.0, 88.0, 92.0, 89.0, 90.0, 88.0], "value": 89.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 98.0, 98.0, 98.0, 99.0, 99.0, 98.0, 98.0, 98.0], "value": 98.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 85.0, 100.0, 130.0, 84.0, 84.0, 86.0, 83.0], "value": 90.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 54.0, 55.0, 55.0, 58.0, 55.0, 55.0, 56.0, 55.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 70.0, 75.0, 75.0, 71.0, 69.0, 72.0, 70.0, 71.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 90.0, 89.0, 95.0, 107.0, 108.0, 87.0, 90.0, 89.0], "value": 92.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 161.0, 140.0, 140.0, 145.0, 144.0, 145.0, 230.0, 145.0], "value": 154.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 189.0, 236.0, 183.0, 173.0, 172.0, 171.0, 169.0, 171.0], "value": 180.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 66.0, 68.0, 69.0, 69.0, 67.0, 68.0, 68.0, 68.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1484.2, "alertThreshold": 2.0}]} 05:42:30 INFO - Return code: 0 05:42:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:42:31 INFO - # TBPL SUCCESS # 05:42:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:42:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:42:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:42:31 INFO - 2016-10-13 05:42:31.035 screenresolution[1667:9763] starting screenresolution argv=screenresolution get 05:42:31 INFO - 2016-10-13 05:42:31.037 screenresolution[1667:9763] Display 0: 1600x1200x32@60 05:42:31 INFO - 2016-10-13 05:42:31.044 screenresolution[1668:9766] starting screenresolution argv=screenresolution list 05:42:31 INFO - Available Modes on Display 0 05:42:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:42:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:42:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:42:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:42:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:42:31 INFO - Intel Iris: 05:42:31 INFO - Chipset Model: Intel Iris 05:42:31 INFO - Type: GPU 05:42:31 INFO - Bus: Built-In 05:42:31 INFO - VRAM (Dynamic, Max): 1536 MB 05:42:31 INFO - Vendor: Intel (0x8086) 05:42:31 INFO - Device ID: 0x0a2e 05:42:31 INFO - Revision ID: 0x0009 05:42:31 INFO - Displays: 05:42:31 INFO - Display: 05:42:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:42:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:42:31 INFO - Display Serial Number: 1600x1200 60 05:42:31 INFO - Main Display: Yes 05:42:31 INFO - Mirror: Off 05:42:31 INFO - Online: Yes 05:42:31 INFO - Rotation: Supported 05:42:31 INFO - Return code: 0 05:42:31 INFO - Running post-action listener: _resource_record_post_action 05:42:31 INFO - [mozharness: 2016-10-13 12:42:31.277878Z] Finished run-tests step (success) 05:42:31 INFO - Running post-run listener: _resource_record_post_run 05:42:31 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 38511616; Write bytes: 708553216; Read time: 377; Write time: 1841 05:42:31 INFO - TinderboxPrint: CPU usage
53.5% 05:42:31 INFO - TinderboxPrint: I/O read bytes / time
38,511,616 / 377 05:42:31 INFO - TinderboxPrint: I/O write bytes / time
708,553,216 / 1,841 05:42:31 INFO - TinderboxPrint: CPU idle
2,260.1 (46.5%) 05:42:31 INFO - TinderboxPrint: CPU system
971.0 (20.0%) 05:42:31 INFO - TinderboxPrint: CPU user
1,626.6 (33.5%) 05:42:31 INFO - TinderboxPrint: Swap in / out
445,423,616 / 0 05:42:31 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 409121280; Write bytes: 398102016; Read time: 31083; Write time: 672 05:42:31 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 31104000; Write bytes: 296418304; Read time: 288; Write time: 1130 05:42:31 INFO - Running post-run listener: _upload_blobber_files 05:42:31 INFO - Blob upload gear active. 05:42:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:42:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:42:31 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:42:31 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:42:32 INFO - (blobuploader) - INFO - Open directory for files ... 05:42:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:42:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:42:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:42:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:42:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:42:32 INFO - (blobuploader) - INFO - Done attempting. 05:42:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:42:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:42:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:42:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:42:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:42:34 INFO - (blobuploader) - INFO - Done attempting. 05:42:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:42:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:42:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:42:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:42:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:42:38 INFO - (blobuploader) - INFO - Done attempting. 05:42:38 INFO - (blobuploader) - INFO - Iteration through files over. 05:42:38 INFO - Return code: 0 05:42:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:42:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:42:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bad0255eddcea229a6979b1d62f180307d6d147ac8407b694daf7b3fe3af8c3bbc1565f805e041c4eb42a274eeb4b21a88cab9cb9da5e1f536bf0afe5e145a73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/846d0309e31d8606c707bd6697eed6a0d203503ad0b6baca050189e0f8f9172ce53370092cdb2c4ef33e3e00a8ceb72db43dbb60a7dad6e0e47a8d954e648344", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4d614bf818b813822f2720605e46cd5c834af62a565aee6d06504b44ecabcbbe7bc2b5f1d3046079000235f34cfdd122dd4a0f47fd6641fa9c815490e85814f4"} 05:42:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:42:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:42:38 INFO - Contents: 05:42:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bad0255eddcea229a6979b1d62f180307d6d147ac8407b694daf7b3fe3af8c3bbc1565f805e041c4eb42a274eeb4b21a88cab9cb9da5e1f536bf0afe5e145a73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/846d0309e31d8606c707bd6697eed6a0d203503ad0b6baca050189e0f8f9172ce53370092cdb2c4ef33e3e00a8ceb72db43dbb60a7dad6e0e47a8d954e648344", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4d614bf818b813822f2720605e46cd5c834af62a565aee6d06504b44ecabcbbe7bc2b5f1d3046079000235f34cfdd122dd4a0f47fd6641fa9c815490e85814f4"} 05:42:38 INFO - Running post-run listener: copy_logs_to_upload_dir 05:42:38 INFO - Copying logs to upload dir... 05:42:38 INFO - mkdir: /builds/slave/test/build/upload/logs 05:42:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.010110 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-10-13 05:42:38.470284) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 05:42:38.474264) ========= 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.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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/Fx-Team/sha512/bad0255eddcea229a6979b1d62f180307d6d147ac8407b694daf7b3fe3af8c3bbc1565f805e041c4eb42a274eeb4b21a88cab9cb9da5e1f536bf0afe5e145a73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/846d0309e31d8606c707bd6697eed6a0d203503ad0b6baca050189e0f8f9172ce53370092cdb2c4ef33e3e00a8ceb72db43dbb60a7dad6e0e47a8d954e648344", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4d614bf818b813822f2720605e46cd5c834af62a565aee6d06504b44ecabcbbe7bc2b5f1d3046079000235f34cfdd122dd4a0f47fd6641fa9c815490e85814f4"} build_url:https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011214 build_url: 'https://queue.taskcluster.net/v1/task/J7yPwo8XR4a5h8x4s_zVjQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bad0255eddcea229a6979b1d62f180307d6d147ac8407b694daf7b3fe3af8c3bbc1565f805e041c4eb42a274eeb4b21a88cab9cb9da5e1f536bf0afe5e145a73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/846d0309e31d8606c707bd6697eed6a0d203503ad0b6baca050189e0f8f9172ce53370092cdb2c4ef33e3e00a8ceb72db43dbb60a7dad6e0e47a8d954e648344", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4d614bf818b813822f2720605e46cd5c834af62a565aee6d06504b44ecabcbbe7bc2b5f1d3046079000235f34cfdd122dd4a0f47fd6641fa9c815490e85814f4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 05:42:38.506278) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:42:38.506596) ========= 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.rqmcuHE2VL/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.1Yfa5m7KDs/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.005205 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:42:38.554779) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 05:42:38.555130) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 05:42:38.555453) ========= ========= Total master_lag: 0.17 =========