builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0025 starttime: 1476317974.71 results: success (0) buildid: 20161012142646 builduid: 3edd39fec5a24866bc2e60ec60f520cc revision: 22be4ae74653b25186665f22e52a50e7027fd36b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 17:19:34.714109) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 17:19:34.714590) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 17:19:34.714862) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 17:19:34.743151) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:19:34.743552) ========= 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.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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-12 17:19:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 166M=0s 2016-10-12 17:19:34 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120960 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:19:34.892336) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:19:34.892925) ========= 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.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.027013 ========= master_lag: 0.54 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:19:35.462639) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 17:19:35.463036) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 22be4ae74653b25186665f22e52a50e7027fd36b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 22be4ae74653b25186665f22e52a50e7027fd36b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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-12 17:19:35,541 truncating revision to first 12 chars 2016-10-12 17:19:35,541 Setting DEBUG logging. 2016-10-12 17:19:35,541 attempt 1/10 2016-10-12 17:19:35,541 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/22be4ae74653?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 17:19:36,877 unpacking tar archive at: mozilla-central-22be4ae74653/testing/mozharness/ program finished with exit code 0 elapsedTime=1.630201 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 17:19:37.122147) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 17:19:37.122527) ========= script_repo_revision: 22be4ae74653b25186665f22e52a50e7027fd36b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 17:19:37.123095) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 17:19:37.123372) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 17:19:37.142925) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-12 17:19:37.143612) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 17:19:37 INFO - MultiFileLogger online at 20161012 17:19:37 in /builds/slave/test 17:19:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:19:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:19:37 INFO - {'append_to_log': False, 17:19:37 INFO - 'base_work_dir': '/builds/slave/test', 17:19:37 INFO - 'blob_upload_branch': 'Firefox', 17:19:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:19:37 INFO - 'branch': 'Firefox', 17:19:37 INFO - 'buildbot_json_path': 'buildprops.json', 17:19:37 INFO - 'config_files': ('talos/mac_config.py',), 17:19:37 INFO - 'default_actions': ('clobber', 17:19:37 INFO - 'read-buildbot-config', 17:19:37 INFO - 'download-and-extract', 17:19:37 INFO - 'populate-webroot', 17:19:37 INFO - 'create-virtualenv', 17:19:37 INFO - 'install', 17:19:37 INFO - 'run-tests'), 17:19:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:19:37 INFO - 'download_minidump_stackwalk': True, 17:19:37 INFO - 'download_symbols': 'ondemand', 17:19:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:19:37 INFO - 'tooltool.py': '/tools/tooltool.py', 17:19:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:19:37 INFO - '/tools/misc-python/virtualenv.py')}, 17:19:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:19:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:19:37 INFO - 'installer_path': 'installer.exe', 17:19:37 INFO - 'log_level': 'info', 17:19:37 INFO - 'log_name': 'talos', 17:19:37 INFO - 'log_to_console': True, 17:19:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:19:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:19:37 INFO - 'opt_config_files': (), 17:19:37 INFO - 'pip_index': False, 17:19:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:19:37 INFO - 'cmd': ('bash', 17:19:37 INFO - '-c', 17:19:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:19:37 INFO - 'enabled': True, 17:19:37 INFO - 'halt_on_failure': False, 17:19:37 INFO - 'name': 'check_screen_resolution'},), 17:19:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:19:37 INFO - 'cmd': ('bash', 17:19:37 INFO - '-c', 17:19:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:19:37 INFO - 'enabled': True, 17:19:37 INFO - 'halt_on_failure': False, 17:19:37 INFO - 'name': 'check_screen_resolution'},), 17:19:37 INFO - 'run_cmd_checks_enabled': True, 17:19:37 INFO - 'sps_profile': False, 17:19:37 INFO - 'sps_profile_interval': 0, 17:19:37 INFO - 'suite': 'dromaeojs', 17:19:37 INFO - 'system_bits': '32', 17:19:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:19:37 INFO - 'title': 't-yosemite-r7-0025', 17:19:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:19:37 INFO - 'use_talos_json': True, 17:19:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:19:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:19:37 INFO - 'work_dir': 'build'} 17:19:37 INFO - [mozharness: 2016-10-13 00:19:37.355150Z] Running clobber step. 17:19:37 INFO - Running pre-action listener: _resource_record_pre_action 17:19:37 INFO - Running main action method: clobber 17:19:37 INFO - rmtree: /builds/slave/test/build 17:19:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:19:39 INFO - Running post-action listener: _resource_record_post_action 17:19:39 INFO - [mozharness: 2016-10-13 00:19:39.554917Z] Finished clobber step (success) 17:19:39 INFO - [mozharness: 2016-10-13 00:19:39.555050Z] Running read-buildbot-config step. 17:19:39 INFO - Running pre-action listener: _resource_record_pre_action 17:19:39 INFO - Running main action method: read_buildbot_config 17:19:39 INFO - Using buildbot properties: 17:19:39 INFO - { 17:19:39 INFO - "project": "", 17:19:39 INFO - "product": "firefox", 17:19:39 INFO - "script_repo_revision": "production", 17:19:39 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 17:19:39 INFO - "repository": "", 17:19:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 17:19:39 INFO - "buildid": "20161012142646", 17:19:39 INFO - "pgo_build": "False", 17:19:39 INFO - "basedir": "/builds/slave/test", 17:19:39 INFO - "buildnumber": 43, 17:19:39 INFO - "slavename": "t-yosemite-r7-0025", 17:19:39 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:19:39 INFO - "platform": "yosemite_r7", 17:19:39 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:19:39 INFO - "branch": "mozilla-central", 17:19:39 INFO - "repo_path": "mozilla-central", 17:19:39 INFO - "revision": "22be4ae74653b25186665f22e52a50e7027fd36b", 17:19:39 INFO - "stage_platform": "macosx64", 17:19:39 INFO - "builduid": "3edd39fec5a24866bc2e60ec60f520cc", 17:19:39 INFO - "slavebuilddir": "test" 17:19:39 INFO - } 17:19:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 17:19:39 INFO - Running post-action listener: _resource_record_post_action 17:19:39 INFO - [mozharness: 2016-10-13 00:19:39.558326Z] Finished read-buildbot-config step (success) 17:19:39 INFO - [mozharness: 2016-10-13 00:19:39.558442Z] Running download-and-extract step. 17:19:39 INFO - Running pre-action listener: _resource_record_pre_action 17:19:39 INFO - Running main action method: download_and_extract 17:19:39 INFO - mkdir: /builds/slave/test/build/tests 17:19: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')]} 17:19:39 INFO - trying https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 17:19:39 INFO - Downloading https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 17:19:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 17:19:40 INFO - Downloaded 1199 bytes. 17:19:40 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 17:19:40 INFO - Using the following test package requirements: 17:19:40 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:19:40 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:19:40 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 17:19:40 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:19:40 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 17:19:40 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:19:40 INFO - u'jsshell-mac.zip'], 17:19:40 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:19:40 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 17:19:40 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:19:40 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:19:40 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 17:19:40 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:19:40 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 17:19:40 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:19:40 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 17:19:40 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:19:40 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 17:19:40 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:19:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:19:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:19:40 INFO - Fetch https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:19:41 INFO - Http code: 200 17:19:41 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 17:19:41 INFO - x-amz-cf-id: BGIGdXkkLRF4M4k7IqOW_uAZw15KxGOwdqfOiZ0jEBvs0uEZXrRUng== 17:19:41 INFO - x-amz-version-id: v3mkXGCLkHlcoaHTnbZ5xH3GNRuiXOWh 17:19:41 INFO - Expected file size: 16511087 17:19:41 INFO - Obtained file size: 16511087 17:19:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:19:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:19:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:19:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:19:48 INFO - Http code: 200 17:19:48 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 17:19:48 INFO - x-amz-cf-id: gimho0R2IebZkJ17wCGGQYEbatVqLpUjOECzlKas2qiVNnaP4NBLEg== 17:19:48 INFO - x-amz-version-id: v3mkXGCLkHlcoaHTnbZ5xH3GNRuiXOWh 17:19:48 INFO - Expected file size: 16511087 17:19:48 INFO - Obtained file size: 16511087 17:19:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 17:19:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:19:55 INFO - Fetch https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 17:19:56 INFO - Http code: 200 17:19:56 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 17:19:56 INFO - x-amz-cf-id: PneKMRZ8UsK6yC-LRW5UzOx9RlKWyqpKS903UBM_ZB6qBdGFlGIsDQ== 17:19:56 INFO - x-amz-version-id: EtsTVvwp9CDNkfYCpADHadWXlxwSfwh5 17:19:56 INFO - Expected file size: 14409006 17:19:56 INFO - Obtained file size: 14409006 17:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:56 INFO - trying https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:19:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 17:19:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 17:19:59 INFO - Downloaded 111707728 bytes. 17:19:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:19:59 INFO - mkdir: /builds/slave/test/properties 17:19:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:19:59 INFO - Writing to file /builds/slave/test/properties/build_url 17:19:59 INFO - Contents: 17:19:59 INFO - build_url:https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:20:00 INFO - Running post-action listener: _resource_record_post_action 17:20:00 INFO - Running post-action listener: set_extra_try_arguments 17:20:00 INFO - [mozharness: 2016-10-13 00:20:00.414505Z] Finished download-and-extract step (success) 17:20:00 INFO - [mozharness: 2016-10-13 00:20:00.414632Z] Running populate-webroot step. 17:20:00 INFO - Running pre-action listener: _resource_record_pre_action 17:20:00 INFO - Running main action method: populate_webroot 17:20:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:20:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:20:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:20:00 INFO - '--tppagecycles', 17:20:00 INFO - '7'], 17:20:00 INFO - 'tests': ['tsvgm']}}, 17:20:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:20:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:20:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:20:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:20:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:20:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:20:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:00 INFO - 'talos_options': ['--disable-e10s'], 17:20:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:20:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:20:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:00 INFO - 'talos_options': ['--disable-e10s'], 17:20:00 INFO - 'tests': ['damp', 'tps']}, 17:20:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:00 INFO - 'tests': ['damp', 'tps']}, 17:20:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:20:00 INFO - 'tests': ['dromaeo_dom']}, 17:20:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:20:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:20:00 INFO - 'tests': ['basic_compositor_video']}, 17:20:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:20:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:20:00 INFO - 'tests': ['a11yr', 17:20:00 INFO - 'ts_paint', 17:20:00 INFO - 'tpaint', 17:20:00 INFO - 'sessionrestore', 17:20:00 INFO - 'sessionrestore_no_auto_restore', 17:20:00 INFO - 'tabpaint']}, 17:20:00 INFO - 'other-e10s': {'tests': ['a11yr', 17:20:00 INFO - 'ts_paint', 17:20:00 INFO - 'tpaint', 17:20:00 INFO - 'sessionrestore', 17:20:00 INFO - 'sessionrestore_no_auto_restore', 17:20:00 INFO - 'tabpaint']}, 17:20:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:20:00 INFO - 'ts_paint', 17:20:00 INFO - 'tpaint', 17:20:00 INFO - 'sessionrestore', 17:20:00 INFO - 'sessionrestore_no_auto_restore', 17:20:00 INFO - 'tabpaint']}, 17:20:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:20:00 INFO - 'ts_paint', 17:20:00 INFO - 'tpaint', 17:20:00 INFO - 'sessionrestore', 17:20:00 INFO - 'sessionrestore_no_auto_restore', 17:20:00 INFO - 'tabpaint']}, 17:20:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:20:00 INFO - 'tests': ['a11yr', 17:20:00 INFO - 'ts_paint', 17:20:00 INFO - 'tpaint', 17:20:00 INFO - 'sessionrestore', 17:20:00 INFO - 'sessionrestore_no_auto_restore', 17:20:00 INFO - 'tabpaint']}, 17:20:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:20:00 INFO - 'tests': ['a11yr', 17:20:00 INFO - 'ts_paint', 17:20:00 INFO - 'tpaint', 17:20:00 INFO - 'sessionrestore', 17:20:00 INFO - 'sessionrestore_no_auto_restore', 17:20:00 INFO - 'tabpaint']}, 17:20:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:20:00 INFO - 'tests': ['tsvgx', 17:20:00 INFO - 'tsvgr_opacity', 17:20:00 INFO - 'tart', 17:20:00 INFO - 'tscrollx', 17:20:00 INFO - 'cart']}, 17:20:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:20:00 INFO - 'tsvgr_opacity', 17:20:00 INFO - 'tart', 17:20:00 INFO - 'tscrollx', 17:20:00 INFO - 'cart']}, 17:20:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:00 INFO - 'talos_options': ['--disable-e10s'], 17:20:00 INFO - 'tests': ['tp5o']}, 17:20:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:00 INFO - 'tests': ['tp5o']}, 17:20:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:00 INFO - 'talos_options': ['--disable-e10s', 17:20:00 INFO - '--xperf_path', 17:20:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:20:00 INFO - 'tests': ['tp5n']}, 17:20:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:00 INFO - 'talos_options': ['--xperf_path', 17:20:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:20:00 INFO - 'tests': ['tp5n']}}, 17:20:00 INFO - 'talos.zip': {'path': '', 17:20:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:20:00 INFO - Running post-action listener: _resource_record_post_action 17:20:00 INFO - [mozharness: 2016-10-13 00:20:00.433491Z] Finished populate-webroot step (success) 17:20:00 INFO - [mozharness: 2016-10-13 00:20:00.433600Z] Running create-virtualenv step. 17:20:00 INFO - Running pre-action listener: _resource_record_pre_action 17:20:00 INFO - Running main action method: create_virtualenv 17:20:00 INFO - Creating virtualenv /builds/slave/test/build/venv 17:20:00 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:20:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:20:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:20:00 INFO - Using real prefix '/tools/python27' 17:20:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:20:01 INFO - Installing distribute.............................................................................................................................................................................................done. 17:20:04 INFO - Installing pip.................done. 17:20:04 INFO - Return code: 0 17:20:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:20:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:20:04 INFO - Reading from file tmpfile_stdout 17:20:04 INFO - Output received: 17:20:04 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:20:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:20: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')]} 17:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20: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')]} 17:20:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:04 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 0x10c6e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c65d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a8310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fbd30>, '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 0x10c321db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f3b20>, '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.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:20:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:20:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:20:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fu4LCLB3fB/Render', 17:20:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:04 INFO - 'HOME': '/Users/cltbld', 17:20:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:04 INFO - 'LOGNAME': 'cltbld', 17:20:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:04 INFO - 'MOZ_NO_REMOTE': '1', 17:20:04 INFO - 'NO_EM_RESTART': '1', 17:20:04 INFO - 'PAGER': '/bin/cat', 17:20:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:04 INFO - 'PWD': '/builds/slave/test', 17:20:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:04 INFO - 'SHELL': '/bin/bash', 17:20:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JX0FBRHbDp/Listeners', 17:20:04 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 17:20:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:04 INFO - 'USER': 'cltbld', 17:20:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:04 INFO - 'XPC_FLAGS': '0x0', 17:20:04 INFO - 'XPC_SERVICE_NAME': '0', 17:20:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:20:04 INFO - Cleaning up... 17:20:04 INFO - Return code: 0 17:20:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:20: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')]} 17:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20: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')]} 17:20:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:04 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 0x10c6e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c65d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a8310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fbd30>, '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 0x10c321db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f3b20>, '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.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:20:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:20:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:20:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fu4LCLB3fB/Render', 17:20:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:04 INFO - 'HOME': '/Users/cltbld', 17:20:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:04 INFO - 'LOGNAME': 'cltbld', 17:20:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:04 INFO - 'MOZ_NO_REMOTE': '1', 17:20:04 INFO - 'NO_EM_RESTART': '1', 17:20:04 INFO - 'PAGER': '/bin/cat', 17:20:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:04 INFO - 'PWD': '/builds/slave/test', 17:20:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:04 INFO - 'SHELL': '/bin/bash', 17:20:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JX0FBRHbDp/Listeners', 17:20:04 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 17:20:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:04 INFO - 'USER': 'cltbld', 17:20:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:04 INFO - 'XPC_FLAGS': '0x0', 17:20:04 INFO - 'XPC_SERVICE_NAME': '0', 17:20:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:04 INFO - Downloading/unpacking psutil>=3.1.1 17:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:20:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:08 INFO - Installing collected packages: psutil 17:20:08 INFO - Running setup.py install for psutil 17:20:08 INFO - building 'psutil._psutil_osx' extension 17:20:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:20:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:20:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:20:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:20:09 INFO - building 'psutil._psutil_posix' extension 17:20:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:20:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:20:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:20:09 INFO - ^ 17:20:09 INFO - 1 warning generated. 17:20:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:20:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:09 INFO - Successfully installed psutil 17:20:09 INFO - Cleaning up... 17:20:09 INFO - Return code: 0 17:20:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:20: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')]} 17:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20: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')]} 17:20:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:09 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 0x10c6e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c65d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a8310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fbd30>, '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 0x10c321db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f3b20>, '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.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:20:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:20:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fu4LCLB3fB/Render', 17:20:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:09 INFO - 'HOME': '/Users/cltbld', 17:20:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:09 INFO - 'LOGNAME': 'cltbld', 17:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:09 INFO - 'MOZ_NO_REMOTE': '1', 17:20:09 INFO - 'NO_EM_RESTART': '1', 17:20:09 INFO - 'PAGER': '/bin/cat', 17:20:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:09 INFO - 'PWD': '/builds/slave/test', 17:20:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:09 INFO - 'SHELL': '/bin/bash', 17:20:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JX0FBRHbDp/Listeners', 17:20:09 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 17:20:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:09 INFO - 'USER': 'cltbld', 17:20:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:09 INFO - 'XPC_FLAGS': '0x0', 17:20:09 INFO - 'XPC_SERVICE_NAME': '0', 17:20:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:20:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:20:12 INFO - Installing collected packages: mozsystemmonitor 17:20:12 INFO - Running setup.py install for mozsystemmonitor 17:20:12 INFO - Successfully installed mozsystemmonitor 17:20:12 INFO - Cleaning up... 17:20:12 INFO - Return code: 0 17:20:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:20:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:12 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 0x10c6e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c65d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a8310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fbd30>, '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 0x10c321db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f3b20>, '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.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:20:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:20:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:20:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fu4LCLB3fB/Render', 17:20:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:12 INFO - 'HOME': '/Users/cltbld', 17:20:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:12 INFO - 'LOGNAME': 'cltbld', 17:20:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:12 INFO - 'MOZ_NO_REMOTE': '1', 17:20:12 INFO - 'NO_EM_RESTART': '1', 17:20:12 INFO - 'PAGER': '/bin/cat', 17:20:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:12 INFO - 'PWD': '/builds/slave/test', 17:20:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:12 INFO - 'SHELL': '/bin/bash', 17:20:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JX0FBRHbDp/Listeners', 17:20:12 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 17:20:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:12 INFO - 'USER': 'cltbld', 17:20:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:12 INFO - 'XPC_FLAGS': '0x0', 17:20:12 INFO - 'XPC_SERVICE_NAME': '0', 17:20:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:12 INFO - Downloading/unpacking jsonschema==2.5.1 17:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:20:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:15 INFO - Downloading functools32-3.2.3-2.tar.gz 17:20:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:20:15 INFO - warning: no files found matching '*.txt' 17:20:15 INFO - no previously-included directories found matching 'build' 17:20:15 INFO - no previously-included directories found matching 'dist' 17:20:15 INFO - no previously-included directories found matching '.git*' 17:20:15 INFO - Installing collected packages: jsonschema, functools32 17:20:16 INFO - Running setup.py install for functools32 17:20:16 INFO - warning: no files found matching '*.txt' 17:20:16 INFO - no previously-included directories found matching 'build' 17:20:16 INFO - no previously-included directories found matching 'dist' 17:20:16 INFO - no previously-included directories found matching '.git*' 17:20:16 INFO - Successfully installed jsonschema functools32 17:20:16 INFO - Cleaning up... 17:20:16 INFO - Return code: 0 17:20:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:20:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:16 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 0x10c6e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c65d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a8310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fbd30>, '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 0x10c321db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f3b20>, '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.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:20:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:20:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:20:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fu4LCLB3fB/Render', 17:20:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:16 INFO - 'HOME': '/Users/cltbld', 17:20:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:16 INFO - 'LOGNAME': 'cltbld', 17:20:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:16 INFO - 'MOZ_NO_REMOTE': '1', 17:20:16 INFO - 'NO_EM_RESTART': '1', 17:20:16 INFO - 'PAGER': '/bin/cat', 17:20:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:16 INFO - 'PWD': '/builds/slave/test', 17:20:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:16 INFO - 'SHELL': '/bin/bash', 17:20:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JX0FBRHbDp/Listeners', 17:20:16 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 17:20:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:16 INFO - 'USER': 'cltbld', 17:20:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:16 INFO - 'XPC_FLAGS': '0x0', 17:20:16 INFO - 'XPC_SERVICE_NAME': '0', 17:20:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:20:16 INFO - Cleaning up... 17:20:16 INFO - Return code: 0 17:20:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:20:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:16 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 0x10c6e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c65d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a8310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fbd30>, '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 0x10c321db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f3b20>, '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.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:20:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:20:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:20:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fu4LCLB3fB/Render', 17:20:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:16 INFO - 'HOME': '/Users/cltbld', 17:20:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:16 INFO - 'LOGNAME': 'cltbld', 17:20:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:16 INFO - 'MOZ_NO_REMOTE': '1', 17:20:16 INFO - 'NO_EM_RESTART': '1', 17:20:16 INFO - 'PAGER': '/bin/cat', 17:20:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:16 INFO - 'PWD': '/builds/slave/test', 17:20:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:16 INFO - 'SHELL': '/bin/bash', 17:20:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JX0FBRHbDp/Listeners', 17:20:16 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 17:20:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:16 INFO - 'USER': 'cltbld', 17:20:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:16 INFO - 'XPC_FLAGS': '0x0', 17:20:16 INFO - 'XPC_SERVICE_NAME': '0', 17:20:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:16 INFO - Downloading/unpacking blobuploader==1.2.4 17:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:19 INFO - Downloading blobuploader-1.2.4.tar.gz 17:20:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:20:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:20:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:20 INFO - Downloading docopt-0.6.1.tar.gz 17:20:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:20:20 INFO - Installing collected packages: blobuploader, requests, docopt 17:20:20 INFO - Running setup.py install for blobuploader 17:20:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:20:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:20:20 INFO - Running setup.py install for requests 17:20:21 INFO - Running setup.py install for docopt 17:20:21 INFO - Successfully installed blobuploader requests docopt 17:20:21 INFO - Cleaning up... 17:20:21 INFO - Return code: 0 17:20:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:20: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')]} 17:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20: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')]} 17:20:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:21 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 0x10c6e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c65d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a8310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fbd30>, '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 0x10c321db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f3b20>, '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.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:20:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:20:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:20:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fu4LCLB3fB/Render', 17:20:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:21 INFO - 'HOME': '/Users/cltbld', 17:20:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:21 INFO - 'LOGNAME': 'cltbld', 17:20:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:21 INFO - 'MOZ_NO_REMOTE': '1', 17:20:21 INFO - 'NO_EM_RESTART': '1', 17:20:21 INFO - 'PAGER': '/bin/cat', 17:20:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:21 INFO - 'PWD': '/builds/slave/test', 17:20:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:21 INFO - 'SHELL': '/bin/bash', 17:20:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JX0FBRHbDp/Listeners', 17:20:21 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 17:20:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:21 INFO - 'USER': 'cltbld', 17:20:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:21 INFO - 'XPC_FLAGS': '0x0', 17:20:21 INFO - 'XPC_SERVICE_NAME': '0', 17:20:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:20:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-2pWdT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:20:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-8GYhkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:20:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-zZkk8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:20:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-kkeZbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:20:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-8rxiDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:20:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-IHs5Gb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:20:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-vGVgvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:20:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-5u1YkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:20:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-R6r_mS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:20:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-WG8qjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:20:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-sQ5f6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:20:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Vz0V4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:20:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-w6hwWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:20:23 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-8W4owf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:23 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-A5SxTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:20:23 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-UbSQIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:20:23 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-zFp2eT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:20:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:20:23 INFO - Running setup.py install for manifestparser 17:20:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:20:23 INFO - Running setup.py install for mozcrash 17:20:23 INFO - Running setup.py install for mozdebug 17:20:23 INFO - Running setup.py install for mozdevice 17:20:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:20:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:20:24 INFO - Running setup.py install for mozfile 17:20:24 INFO - Running setup.py install for mozhttpd 17:20:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:20:24 INFO - Running setup.py install for mozinfo 17:20:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:20:24 INFO - Running setup.py install for mozInstall 17:20:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:20:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:20:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:20:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:20:24 INFO - Running setup.py install for mozleak 17:20:24 INFO - Running setup.py install for mozlog 17:20:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:20:24 INFO - Running setup.py install for moznetwork 17:20:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:20:25 INFO - Running setup.py install for mozprocess 17:20:25 INFO - Running setup.py install for mozprofile 17:20:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:20:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:20:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:20:25 INFO - Running setup.py install for mozrunner 17:20:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:20:25 INFO - Running setup.py install for mozscreenshot 17:20:25 INFO - Running setup.py install for moztest 17:20:25 INFO - Running setup.py install for mozversion 17:20:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:20:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:20:25 INFO - Cleaning up... 17:20:26 INFO - Return code: 0 17:20:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:20:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:26 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 0x10c6e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c65d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a8310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fbd30>, '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 0x10c321db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f3b20>, '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.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:20:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:20:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:20:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fu4LCLB3fB/Render', 17:20:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:26 INFO - 'HOME': '/Users/cltbld', 17:20:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:26 INFO - 'LOGNAME': 'cltbld', 17:20:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:26 INFO - 'MOZ_NO_REMOTE': '1', 17:20:26 INFO - 'NO_EM_RESTART': '1', 17:20:26 INFO - 'PAGER': '/bin/cat', 17:20:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:26 INFO - 'PWD': '/builds/slave/test', 17:20:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:26 INFO - 'SHELL': '/bin/bash', 17:20:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JX0FBRHbDp/Listeners', 17:20:26 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 17:20:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:26 INFO - 'USER': 'cltbld', 17:20:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:26 INFO - 'XPC_FLAGS': '0x0', 17:20:26 INFO - 'XPC_SERVICE_NAME': '0', 17:20:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:20:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-KloGF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:20:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ixvM6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:20:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-L0o44n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:20:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-dNYS95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:20:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-F15n2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:20:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-piexzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:20:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ORr32V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:20:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:20:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-dMRb6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:20:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:20:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-iC1sFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:20:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:20:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-BNq04G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:20:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:20:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-AEnOqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:20:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:20:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-kC38al-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:20:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:20:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-9PfHnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:20:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:20:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-48AZXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:20:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-XdG6H0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:20:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:20:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-bj3HVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:20:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:20:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-whOWWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:20:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:20:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:20:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:20:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:20:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:20:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:20:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:30 INFO - Downloading blessings-1.6.tar.gz 17:20:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:20:30 INFO - Installing collected packages: blessings 17:20:30 INFO - Running setup.py install for blessings 17:20:30 INFO - Successfully installed blessings 17:20:30 INFO - Cleaning up... 17:20:30 INFO - Return code: 0 17:20:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:20:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:20:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:20:31 INFO - Reading from file tmpfile_stdout 17:20:31 INFO - Current package versions: 17:20:31 INFO - blessings == 1.6 17:20:31 INFO - blobuploader == 1.2.4 17:20:31 INFO - docopt == 0.6.1 17:20:31 INFO - functools32 == 3.2.3-2 17:20:31 INFO - jsonschema == 2.5.1 17:20:31 INFO - manifestparser == 1.1 17:20:31 INFO - mozInstall == 1.12 17:20:31 INFO - mozcrash == 0.17 17:20:31 INFO - mozdebug == 0.1 17:20:31 INFO - mozdevice == 0.48 17:20:31 INFO - mozfile == 1.2 17:20:31 INFO - mozhttpd == 0.7 17:20:31 INFO - mozinfo == 0.9 17:20:31 INFO - mozleak == 0.1 17:20:31 INFO - mozlog == 3.4 17:20:31 INFO - moznetwork == 0.27 17:20:31 INFO - mozprocess == 0.23 17:20:31 INFO - mozprofile == 0.28 17:20:31 INFO - mozrunner == 6.12 17:20:31 INFO - mozscreenshot == 0.1 17:20:31 INFO - mozsystemmonitor == 0.3 17:20:31 INFO - moztest == 0.7 17:20:31 INFO - mozversion == 1.4 17:20:31 INFO - psutil == 3.1.1 17:20:31 INFO - requests == 1.2.3 17:20:31 INFO - wsgiref == 0.1.2 17:20:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:32 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 0x10c6e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c65d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a8310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fbd30>, '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 0x10c321db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f3b20>, '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.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:20:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:20:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fu4LCLB3fB/Render', 17:20:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:32 INFO - 'HOME': '/Users/cltbld', 17:20:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:32 INFO - 'LOGNAME': 'cltbld', 17:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:32 INFO - 'MOZ_NO_REMOTE': '1', 17:20:32 INFO - 'NO_EM_RESTART': '1', 17:20:32 INFO - 'PAGER': '/bin/cat', 17:20:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:32 INFO - 'PWD': '/builds/slave/test', 17:20:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:32 INFO - 'SHELL': '/bin/bash', 17:20:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JX0FBRHbDp/Listeners', 17:20:32 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 17:20:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:32 INFO - 'USER': 'cltbld', 17:20:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:32 INFO - 'XPC_FLAGS': '0x0', 17:20:32 INFO - 'XPC_SERVICE_NAME': '0', 17:20:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:20:32 INFO - Cleaning up... 17:20:32 INFO - Return code: 0 17:20:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:32 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 0x10c6e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c65d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a8310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fbd30>, '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 0x10c321db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f3b20>, '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.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:20:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:20:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fu4LCLB3fB/Render', 17:20:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:32 INFO - 'HOME': '/Users/cltbld', 17:20:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:32 INFO - 'LOGNAME': 'cltbld', 17:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:32 INFO - 'MOZ_NO_REMOTE': '1', 17:20:32 INFO - 'NO_EM_RESTART': '1', 17:20:32 INFO - 'PAGER': '/bin/cat', 17:20:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:32 INFO - 'PWD': '/builds/slave/test', 17:20:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:32 INFO - 'SHELL': '/bin/bash', 17:20:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JX0FBRHbDp/Listeners', 17:20:32 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 17:20:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:32 INFO - 'USER': 'cltbld', 17:20:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:32 INFO - 'XPC_FLAGS': '0x0', 17:20:32 INFO - 'XPC_SERVICE_NAME': '0', 17:20:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:20:32 INFO - Cleaning up... 17:20:32 INFO - Return code: 0 17:20:32 INFO - Running post-action listener: _resource_record_post_action 17:20:32 INFO - Running post-action listener: _start_resource_monitoring 17:20:32 INFO - Starting resource monitoring. 17:20:32 INFO - [mozharness: 2016-10-13 00:20:32.991855Z] Finished create-virtualenv step (success) 17:20:32 INFO - [mozharness: 2016-10-13 00:20:32.992840Z] Running install step. 17:20:32 INFO - Running pre-action listener: _resource_record_pre_action 17:20:32 INFO - Running main action method: install 17:20:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:20:33 INFO - Reading from file tmpfile_stdout 17:20:33 INFO - Detecting whether we're running mozinstall >=1.0... 17:20:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:20:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:20:33 INFO - Reading from file tmpfile_stdout 17:20:33 INFO - Output received: 17:20:33 INFO - Usage: mozinstall [options] installer 17:20:33 INFO - Options: 17:20:33 INFO - -h, --help show this help message and exit 17:20:33 INFO - -d DEST, --destination=DEST 17:20:33 INFO - Directory to install application into. [default: 17:20:33 INFO - "/builds/slave/test"] 17:20:33 INFO - --app=APP Application being installed. [default: firefox] 17:20:33 INFO - mkdir: /builds/slave/test/build/application 17:20:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:20:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:21:08 INFO - Reading from file tmpfile_stdout 17:21:08 INFO - Output received: 17:21:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:21:08 INFO - Running post-action listener: _resource_record_post_action 17:21:08 INFO - [mozharness: 2016-10-13 00:21:08.128119Z] Finished install step (success) 17:21:08 INFO - [mozharness: 2016-10-13 00:21:08.128250Z] Running run-tests step. 17:21:08 INFO - Running pre-action listener: _resource_record_pre_action 17:21:08 INFO - Running main action method: run_tests 17:21:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:21:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:21:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:21:08 INFO - 2016-10-12 17:21:08.142 screenresolution[1632:7572] starting screenresolution argv=screenresolution get 17:21:08 INFO - 2016-10-12 17:21:08.145 screenresolution[1632:7572] Display 0: 1600x1200x32@60 17:21:08 INFO - 2016-10-12 17:21:08.151 screenresolution[1633:7574] starting screenresolution argv=screenresolution list 17:21:08 INFO - Available Modes on Display 0 17:21:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:21:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:21:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:21:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:21:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:21:08 INFO - Intel Iris: 17:21:08 INFO - Chipset Model: Intel Iris 17:21:08 INFO - Type: GPU 17:21:08 INFO - Bus: Built-In 17:21:08 INFO - VRAM (Dynamic, Max): 1536 MB 17:21:08 INFO - Vendor: Intel (0x8086) 17:21:08 INFO - Device ID: 0x0a2e 17:21:08 INFO - Revision ID: 0x0009 17:21:08 INFO - Displays: 17:21:08 INFO - Display: 17:21:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:21:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:21:08 INFO - Display Serial Number: 1600x1200 60 17:21:08 INFO - Main Display: Yes 17:21:08 INFO - Mirror: Off 17:21:08 INFO - Online: Yes 17:21:08 INFO - Rotation: Supported 17:21:08 INFO - Return code: 0 17:21:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:21:08 INFO - Python 2.7.3 17:21:08 INFO - Return code: 0 17:21:08 INFO - grabbing minidump binary from tooltool 17:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:08 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 0x10c4fbd30>, '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 0x10c321db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:21:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:21:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:21:08 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:21:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:21:08 INFO - Return code: 0 17:21:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:21:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:21:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:21:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:21:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:21:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0025 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:21:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fu4LCLB3fB/Render', 17:21:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:08 INFO - 'HOME': '/Users/cltbld', 17:21:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:08 INFO - 'LOGNAME': 'cltbld', 17:21:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:21:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:21:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:08 INFO - 'MOZ_NO_REMOTE': '1', 17:21:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:21:08 INFO - 'NO_EM_RESTART': '1', 17:21:08 INFO - 'PAGER': '/bin/cat', 17:21:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:08 INFO - 'PWD': '/builds/slave/test', 17:21:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:21:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:08 INFO - 'SHELL': '/bin/bash', 17:21:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JX0FBRHbDp/Listeners', 17:21:08 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 17:21:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:08 INFO - 'USER': 'cltbld', 17:21:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:08 INFO - 'XPC_FLAGS': '0x0', 17:21:08 INFO - 'XPC_SERVICE_NAME': '0', 17:21:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:21:08 INFO - mozversion application_buildid: 20161012142646 17:21:08 INFO - mozversion application_changeset: 22be4ae74653b25186665f22e52a50e7027fd36b 17:21:08 INFO - mozversion application_display_name: Nightly 17:21:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:21:08 INFO - mozversion application_name: Firefox 17:21:08 INFO - mozversion application_remotingname: firefox 17:21:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:21:08 INFO - mozversion application_vendor: Mozilla 17:21:08 INFO - mozversion application_version: 52.0a1 17:21:08 INFO - mozversion platform_buildid: 20161012142646 17:21:08 INFO - mozversion platform_changeset: 22be4ae74653b25186665f22e52a50e7027fd36b 17:21:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:21:08 INFO - mozversion platform_version: 52.0a1 17:21:08 INFO - using testdate: 1476318068 17:21:08 INFO - actual date: 1476318068 17:21:08 INFO - starting webserver on 'localhost:49266' 17:21:08 INFO - SUITE-START | Running 2 tests 17:21:08 INFO - TEST-START | dromaeo_css 17:21:08 INFO - operating with platform_type : mac_ 17:21:08 INFO - Initialising browser for dromaeo_css test... 17:21:08 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmp2gOLjB/profile http://localhost:49266/getInfo.html) 17:21:11 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 17:21:11 INFO - PROCESS | 1643 | colorDepth:24 17:21:11 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 17:21:11 INFO - PROCESS | 1643 | __metrics 17:21:11 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:21:11 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:21:12 INFO - TEST-INFO | 1643: exit 0 17:21:12 INFO - Browser initialized. 17:21:12 INFO - Running cycle 1/1 for dromaeo_css test... 17:21:12 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmp2gOLjB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:21:24 INFO - PROCESS | 1646 | RSS: Main: 235352064 17:21:24 INFO - PROCESS | 1646 | 17:22:16 INFO - PROCESS | 1646 | 1476318136149 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 17:24:27 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 17:24:31 INFO - PROCESS | 1646 | RSS: Main: 574349312 17:24:31 INFO - PROCESS | 1646 | 17:27:34 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 17:27:34 INFO - PROCESS | 1646 | RSS: Main: 375545856 17:27:34 INFO - PROCESS | 1646 | 17:30:37 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 17:30:37 INFO - PROCESS | 1646 | RSS: Main: 369328128 17:30:37 INFO - PROCESS | 1646 | 17:33:40 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 17:33:40 INFO - PROCESS | 1646 | RSS: Main: 338857984 17:33:40 INFO - PROCESS | 1646 | 17:36:44 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 17:36:44 INFO - PROCESS | 1646 | RSS: Main: 336072704 17:36:44 INFO - PROCESS | 1646 | 17:39:47 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 17:39:48 INFO - PROCESS | 1646 | RSS: Main: 532045824 17:39:48 INFO - PROCESS | 1646 | 17:39:48 INFO - PROCESS | 1646 | __start_tp_report 17:39:48 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 17:39:48 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 17:39:48 INFO - PROCESS | 1646 | |i|pagename|runs| 17:39:48 INFO - PROCESS | 1646 | |0;dojo.html;3330.067581077298;3341.5655964724588;3367.360201561703;3383.9323213535713;3392.0841372829336;5582.888342233153;5656.91514627281;5697.515711164551;5714;5718.942810571898;5662.433756624336;5709.971450142754;5713.857153571163;5716.056850619653;5717.313922329316;5166.793328266865;5204.687718736869;5227.111391063529;5246.790128394879;5247.055530004598;5249.396319423258;5262.158054711247;5274.129768588197;5276.604254680895;5280.339957505312;5164.354455693038;5232.92150617741;5241.109011468059;5261.815836445725;5265.3155089838265;5285.101532739443;5293.126634105368;5305.363356397218;5315.176147697113;5366.91949620756;5084.389873215201;5112.335396398445;5151.072806894757;5223.660462069954;5235.58115350771;5620.887582248373;5635.7463914123555;5673.489385955284;5707.400722924116;5713.3429655589725;6196.659183744892;6201.193844800147;6216.129741836146;6223.222097237845;6225.564210505267;6099.176611157482;6117.644716737907;6191.318954914956;6209.130721698965;6242.875142497124;5702.515286200636;5769.711514424262;5784.855378615484;5792.623479473821;5838.678872662044;5216.58267338612;6243.7814676486105;6252.531060170414;6256.217972753406;6258.499320054385;5978.521718262528;6068.362821903636;6112.266528016667;6125.601835880653;6134.7852825151795;6101.933984285782;6115.696722738819;6142.969285153546;6160.661163635953;6192.59748116371;6118.893302669218;6138.32709113608;6220.937790622126;6226.62640241587;6228.937710622836;6213.968930155411;6222.564420490522;6225.782097626561;6226.59527130735;6232.844178895564;4821.059893320839;4872.123017856749;4877.6829506081995;4936.03747269279;4945.134601444733;3738.719596030309;4393.231184542692;4409;4412.602865742035;4448.688591798607;5686.289213848269;5704.543636509069;5708.628939119027;5716.5140963017775;5728.398518155617;6217.720202590895;6275.654838983808;6284.497240220771;6296.968515157487;6298.464630506367;5597.440255974451;5641.492265696107;5642.181883626851;5716.79991200306;5717.456841600041;4501.7974191161475;5372.409035006146;5377.247185394048;5380.192971054373;5389.353277606712;6245.750169993149;6246.406591373812;6265.780697675559;6268.529860260407;6271.341509141565;6140.9078863816185;6189.566730328834;6209.751609935733;6212.099245609271;6217.689115544114;6271.749130034747;6283.183186185766;6298.496120310456;6298.905516417349;6318.747250109944;6173.876522469615;6192.752289908353;6200.689965501797;6205.348438414082;6206.906896396648;5110.66780659256;6067.696615169312;6255.311915689362;6262.185915830913;6283.937160628335;5995.010823214296;6009.188759517409;6015.39846015395;6017.969910150421;6020.518358531394;5996.370381110095;6001.36985616504;6004.099385092097;6009.54928380382;6011.819645410645;5687.914681279702;5818.709064546841;5829.621074630136;5836.678982655994;5840.883182336244;5948.256467941507;5973.970130149321;5984.012637914695;6001.879962400815;6003;5741.913871292018;5851.707414629337;5924.31870334917;5929.140274660236;6012.128241405059 17:39:48 INFO - PROCESS | 1646 | |1;ext.html;470.8469747332117;517.984460466186;522.7281813457;524.446708722298;549.52740643047;30752.53871191931;32125.35749285013;32135.839320803363;32168.195795105134;32196.67803321966;41931.37102943451;42212.36681449773;42656.36015459763;45100.32349514752;45120;9516.85724714128;9677.322587418883;9681.854772178423;9685.176759975411;9696.612135514571;8598.527081010541;9473.100055494717;9590.664326748565;9598.712038638852;9602.231821454301;10424.79150416995;11183.888161118406;11437.256578322424;11700.648980530597;11737.471813768358;11524.308541487537;11609.883901160965;11612.18714689972;11621.128415368881;11625;11488.36813975231;11564.479598418091;11566.132540059445;11577.826332605018;11578.363190024545;39251;39551.20897582061;39590.802045989876;39591.60408395908;39597;3272.656371081026;3287.7698561100733;3317.137544238491;3332.3668502984597;3413.2320227948762;30812;31458.528122078187;31513.52729709056;31541.684583154332;32097.6790232097;3173.698498642625;3189.3142974260413;3197.2326641605846;3310.4041272570926;3337.0489410517885;483095;484351.1564884341;484983;486180.99999999645;487091;32092.19769505781;32488;32565.02304930856;60046;60589.788204235665;30569.47152642359;34056.31887362239;34388.48417273743;34953.65046349504;34964;14268.14391136535;14414.567562973127;14439.92780036093;14441.133531988115;14446.061006034573;10420.68737937863;11861.881381186251;12251.93874030642;12354.38228088592;12433.37833108359;7194.496385253086;7335.669894854745;7345.1553071397475;7606.125295590967;7726.150123486412;2819.5629677400034;2928.780341474398;3212.951805722916;3257.609086909586;3309.6028476582483;933.8225737109927;963.6868017894212;999.6301368493704;1003.9749006274756;1056.9471526423647;29707.554386684216;29769.85115074454;30141.698583014328;30404.847975760422;30580.69419305823;1035.746242170673;1049.3808652894677;1058.2116323339162;1069.4332004037872;1074;14700.926495367668;14975.775363369557;15439.382424703112;15461.149636770086;15834.524964251092;642086;652117;652155;653111;653175;49864.75067624639;50624.24063639048;50663.24005139926;57968;58519.4148058514;1303.1008604063165;1339.169714776825;1349.473705254971;1362.6389006913175;1364.7202323523654;29855.402891941605;30310.545341820332;30584.235394114436;30804.84597576998;31535.842320788248;49811;50244.74877625588;50473.49526504688;50486.74756626193;50537;412355;414455;634569;644553;654309;25910.74089259083;26800.927962881266;26990.865045674647;41179.58820411757;41627.375589365576;45942.85142871455;46728.29907551457;47019.76490117527;47038;47476;37744.62255377411;37851.432228516045;38408.33888132613;38503.8448846535;39357;29922;31911.883084091944;34859.82570087133;39279.6072039276;39546.813595592175;36879;37136.88589342324;37772.62227377691;37983.43024854684;38219.42670859884;32858.835705821315;33770;34243;34273.82863085669;34567.308653827284;20693.793062069788;22115;24957.500849983262;25459.339784571606;25557.74442255628 17:39:48 INFO - PROCESS | 1646 | |2;jquery.html;315.52198419394614;348.7924684812536;366.8019269594421;371.6655010490558;397.58055251709436;14957.401703931844;15139.167345795977;16122.758158627616;20360.59278814423;20980.685289720666;19340.61318773624;19993.000349982478;20436;20701.585968280626;21158.471038224052;5752.36723960364;6118.510519158456;6119.265688117433;6122.63264204147;6127.632342059468;4740.123077230706;5983.252093488314;6037.094435834616;6045.365236650155;6052.788152414666;5714.228579141804;5975.163477113208;6002.459778619924;6017.06735456005;6018.307894592134;5936.347001829795;5961.1058341248945;6014;6028.367021462771;6038.969805150946;4972.229304457779;5184.9740751296;5199.376074871002;5224.4775522447835;5233;21356.893215533823;25796;26144.86927565369;26194.73805261942;26413.113573988874;1818.4635532517807;1863.2081365419642;1978.357033963953;2102.6425507663735;2111.4193596761015;9895.466202738588;10024.649137280234;10028.89971100287;10511.1591072714;10531.894681053245;1533.5476034569829;1900.695888657822;1906.4090132059107;1915.7796853361763;1940.3790786948182;98103.50948245214;114090;120679;122203;123532.76467235392;8996.640134394682;11339.376334301527;11487.597934072268;11568.826467602992;11676.649700508999;14326.426942922166;14352.712945741236;14368.928155359155;14375.78436323456;14381.568552943429;7258.911163325438;7771.950786643731;8005.8398832024195;8209.302209312274;8488.363372746946;7186.281371862772;7932.801679957932;8193.877091843628;8367.790805229917;8392.706255281037;4394.780260986938;4409.316555933835;4460.219461594208;4471.530489298641;4531.977340113278;1603.3346161343145;1833.7805359436093;1865.552267455828;1906.6949288113835;1913.2442685139292;676.3439463720183;738.8411491529347;845.7758693946109;927.9164875161268;951.695457453622;20903.79096209049;21667.78332216689;21743.565128697337;21771;21823.67264491034;725.8693435181718;887.8490656588395;898.1018981018981;949.715085474355;969.0987381734914;10694;11289.887101128883;11300.886991130148;11307.321560706385;11335.773284534263;547023;549850;558092.2095389497;568683;568983;122896;123560;126973;127835;135223;952.9285303602257;953.1612181280428;975.6243846119295;977.3793641037992;980.6518685866557;18357.3574924877;18526.444206673823;18572;18598.907005464887;18606.627867442305;36068.819655901556;50072.74963625159;51392.74303628458;52443.213351800514;52939.94120117653;74656.25343746493;77179;78656.21343786489;78704.60647696725;80246.5987670058;5711.171880077282;7776.572288524186;7942.6028698566;8150.75547733569;8504.44721093127;9143.817123657622;13007.609771706864;13112.344382781013;13395.263260520765;13492.257925814174;10587.735306617087;11205.943970280096;11266.830997535208;11516.481758321062;11541.480633371351;11182.608608698656;11260.268082574607;11414.143939204427;11417.646534388428;11572.074234061087;9667.984861589432;10695.5187016586;11483.138764592855;11778.293302401884;11869.703257418634;7993.600319984093;8132.471389359673;8212.589370531568;8245.381596380415;8385.832283354177;11069.501872415922;11280.554397251828;11335.489902954221;11375.976162145447;11470.942645286721 17:39:48 INFO - PROCESS | 1646 | |3;mootools.html;1167.9474423650936;1331.9267440290782;1485.836557978622;1631.5350125214316;1868.9160287033521;7893.447458677888;8412.621432035558;8447.619857106421;8890.733278001655;8895.644174233039;8785.516796576201;8848.070952549986;8868.02451730309;8870.512121833297;8910.108989101087;2035.8778473291575;2352.7882490575844;2377.405648587853;2380.9166679166233;2384.463495713468;2303.239930822833;2339.7660233976553;2363.5391098735754;2364.538914911593;2364.988175059122;2327.3483424641045;2354.5997180479358;2357.7995870350956;2364.5861974154454;2370.3481542575755;2302.4474126209757;2323.5120624668757;2331.860088394702;2335.976640233593;2336.4859730859184;2329.6039673255586;2335.5679199348174;2336.707911511061;2336.778006089419;2337.1586228957563;8705.651773929036;8802.691905783331;8807.383483156182;8900;8932.8660070099;5215.400228973679;5422.810201642924;5429;5429.0227759003365;5429.972850135763;3612.295602357541;3646.872359467406;3690.8708195213308;3703.6111208323277;3706.0549559862334;1068.2362111090608;1080.0981180714114;1081.8918108189198;1095.8411030400628;1101.5208384352832;11733.823992640117;11734.178607497479;11849;11852.170348075639;11888.286702797946;4360.4767427908855;4496.258117410591;4615.469221039623;4626.745528995937;4628.282616194495;9126.087391260953;9146.496942668084;9149.954250228839;9160.267178625827;9164.312676549285;5253.080710875582;5257.237700533401;5262.210668399694;5266.973665131726;5267.868303292448;4238.19474299882;4417.889552761207;4457.68796184264;4574.45106587205;4917.385326834145;3086.861091250899;3732.4401339798706;3775.962240377616;3778.981105094457;3809.6190380962234;1141.0928311991986;1142.6914733022038;1143.8284257361463;1151.401271338899;1157.8494795676509;857.5069335132324;873.5675840459013;883.5361435246547;897.1701176411793;897.2552781191596;5805.796797112081;5923.703814809242;5972.820815375546;6119.898418284752;6338.112664227012;799.7960520067462;908.2053203446984;931.1061381074245;979.5004547680734;986.9062439068277;3777.263433630416;3980.383040628707;3982.3230050891416;4015.8393664253686;4076.571959944163;10183.490825458697;10774.569017239379;10942.671719848417;10976.396298203515;11001.339919604849;10613.204009699304;10755.89244107549;10876.238663293494;10879.184061195441;10890.836637450444;7996.960015199887;8236.876446853183;8249.432607804498;8262.669493220325;8273.46222495536;5782.913256301243;5786.276715410574;5865.442782935615;5940.405959404025;6000.669963152069;9245.844269466317;9260.907390926004;9991.90089090214;10048.296619236584;10054.19566434669;9250.907490925005;9618.422894626345;10034;10054.095131437913;10207.795844083224;6951.826204344932;7245.130584329914;7441.479096463196;7542.622868856645;7614.352780013761;8225.670973161246;8227.341812654853;8229.053658829072;8309.875351869849;8324.916750832414;7460.3658689012045;7483.877418387286;7531.096268447822;7539.396848252236;7552.169261381134;7169.139703235645;7228.891566626399;7229.758075793705;7281.745138920112;7451.552906825391;6866.519343645897;6929.792106236821;7018.894716579358;7026.648667566704;7032.718691252293;4766.094442055998;4887.34020907173;4977.129002424634;5011.423686275979;5178.792848286026;4345.847895323648;5001.674891132065;5054.368203974504;5094.617903657167;5124.30821839047 17:39:48 INFO - PROCESS | 1646 | |4;prototype.html;293.7473772555602;294.3069072334652;303.9726424621784;307.20740489536996;310.9362580670963;4300.612944834964;5220.895582088361;5259.184826351921;5308.071087559676;5338.652987555806;3978.508059477696;4892.730899800509;5089.821856235032;5141.460146684601;5152.355955505562;3296.4066468035744;3986.5415477220054;3987.262356464049;3997.8400863965553;4002.5597184309704;3443.311337732451;3457.6715212054814;3991.2815693175216;4020.336644453662;4035.878923632296;3447.931041379171;3896.9817494293743;3936.626020528045;3972.205558888234;3991.3015222335976;3096.714863331726;3271.460209065501;3959.4456776051375;3966.841326346943;3989.68082553395;3491.0923159978333;3502.719782417426;3520.9295814083575;3527.8588856445713;3532.116970757311;4629.310301739874;4858.562729354375;5089.898202035975;5099.464556221617;5129.307543481619;3913.863014794497;4168.54101064628;4421.170450911419;4483.237849565582;4555.681102322838;5023.372078490189;5436.320459942507;5441.238226648273;5443.945560544423;5455.536279416255;3828.617138286177;3829.80850957451;3830.080780612661;3835.137094153821;3865.4201869719486;5525.751341189656;5653.265075540154;5698.572607054488;5703.686297253649;5704.543636509069;3253.9099401700696;3286.9810358788372;3856.2865869814173;3884.533855937306;3894.4937158169823;3491.6682915123024;3967.3652215645357;3978.920421591552;3980.8057582725646;3985.5815139410524;3767.0017445377;4384.81609965314;4513.097380523908;4563.224251877189;4591.494935557086;5465;5482.533984611273;5488.862778430571;5491.885624034775;5524.4199359067525;1256.7486502699496;1260.8676089010705;1288.1240756285629;1330.2018788726687;1366.8223130992908;870.9259712924346;892.2727976698969;914.9085091490931;941.152962333905;945.413843417072;4573.719358579603;5016.698998060128;5209.609279304067;5217.321748172789;5236.371635403776;4852.344933434012;4881.731504767201;5136.306598609214;5533.363663178786;5688.744006519716;4672.415948006499;4761.857144285677;4958.801647934114;5192.506711862367;5211.202135263328;2697.959530607003;2938.9265268368463;2967.679382674689;2970.287131088523;2974.419988102299;4905.852824415273;5642.689652069093;5668.26312579362;5786.860480565295;5837.970810145922;4796.448408433075;4808.591269742041;5791.942080579224;5797.681127538026;5823.56323275756;4644.071185762793;5847.72527384666;5889.852753681193;5908.468237858613;5925.377835327315;4682.484926657997;4901.950980490149;5661.320641523043;5662.329612764162;5699.430056994267;5600.43995600453;5625.353084395183;5640.520555752807;5650;5655.830325090254;5843.853903652443;5850.180974663635;5880.588358814842;5905.498032667271;5993.160957465871;3524.54180956474;3543.81282270432;3724.2551489701627;3922.5489068757456;3981.2833689936087;5557.944420555905;5711.657300561979;5726.134858970262;5763.135529670583;5781.335146458043;4897.632677549127;5597.3003374578175;5606.971965140148;5632.464915832989;5645.548356131581;4859.979404325131;4994.976029913932;5179.1195496766395;5316.282301889196;5549.916751248654;4267.338562522814;5199.818006369759;5430.972845135749;5457.590680698884;5464.23500709893;5406.431944826333;5443.822471011627;5473.343198816167;5476.507114359727;5502.669839809624;4572.4055872736335;4644.58198762113;4733.83431579893;5476.80831170923;5499.972500137474 17:39:48 INFO - PROCESS | 1646 | |5;yui.html;680.3570625758657;698.1656919980625;713.3294702979196;749.4117118062319;764.6198611506236;14811;15181.468648597302;15383.846161538382;15399.846001540009;15521.689566208683;13805.1716896986;13919.095258808196;13924.094933829294;13936.233507157101;13967.511137110203;734.8603765284605;813.8453693798174;821.2977903892175;848.4018766769443;859.4284800607588;860.6170254236868;860.7805009722512;860.8407444622734;861.1732736572898;862.017300277684;905.4295793650024;906.6146887572755;906.8186362727448;906.9637214511412;907.9727608171765;769.3844924060785;899.7030979776658;900.1628485508475;900.8873890763655;902.4810733828042;666.4701562258017;667.1926968368281;668.2582333609689;668.8729141463156;670.661316035399;15095.24523773818;15115.319810608438;15132.167730774803;15135.167565783877;15142.470013549582;13500.392482338319;13571.728565428637;13711.02380309709;13804.654883627889;14023.579292621238;11488.827667584992;11510.597129100524;11533;11706.004989575897;12180.086493513021;3643.9635603643887;3659.9268014639824;3727.7949712765785;3789.1663833956486;3801.144742432958;106447;115981.42009289899;117124.41437792842;119448;121379.3931030348;10307.793844123225;10386.324888882198;10472.424016679004;10638.521266543024;10856.294340867818;22015.22946696858;22030.559388812133;22038.779612203674;22070.448238793837;22085;7327.633618319062;7351.154617218981;7502.099748030271;7532.698692052366;7560.130584982688;4068.959310406858;4122.9381559276635;4161.542230354658;4169.291220492523;4232.195882782262;3623.2753449310244;3707.147356108056;3735.477033215352;3737.2151848111657;3741.401375779862;619.3434958943444;624.8125562331282;629.3423372575669;641.2337256977867;642.4025656139835;534.7085838218103;598.7634884220708;614.4715544631613;630.3098107572201;646.1341801985362;8967.372283940193;9233.676821311223;9305.451829723674;9482.431054136774;9553.379030363005;551.6745120378996;554.5369616370376;562.3701454371048;641.2785616181795;652.6149571752688;4194.874153775391;4327.9716126584735;4567.3377360282575;4640.6751527393435;4708.623310135181;86597;86750;87346.56326718326;128720;141512;72439.63780181065;78508.61625064333;91918.62122068174;118171;118762.21856672157;89392.10607893838;90337;95283;104076.47961760142;104398;8382.497050176766;9710.417374957526;9727.610895564098;9729.173020293354;9773.560189791619;58244.96142634987;60027.69986150216;61943.76112477635;63134;64641;79942.60028700052;90250.54874725585;91297.63053554334;92249.53875230582;96503;12930.353482325658;12942.676433089247;13049.478020879058;13075.542356017493;13317.667058323232;14132.646683832987;14478.855211447752;14515.564533064025;14563.708725825636;15207;13339.132956357806;13388.866111338762;13735.725285494435;14026.150111409741;14204.644883877985;13424.328783560979;13552;13937.790933135808;14048.929755351157;14263.786043209568;11084.279521831055;13049.34753262352;13592.796108058585;13822.723545529234;14019.088759230617;9979.950100249453;10942;11152.330860148093;11271;11366.886331136582;9293.86059209126;9650.469224192737;10460.529276182438;11503.827442588536;11589.82615260755 17:39:48 INFO - PROCESS | 1646 | __end_tp_report 17:39:48 INFO - PROCESS | 1646 | __start_cc_report 17:39:48 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,3906 17:39:48 INFO - PROCESS | 1646 | __end_cc_report 17:39:48 INFO - PROCESS | 1646 | __startTimestamp1476319188242__endTimestamp 17:39:48 INFO - PROCESS | 1646 | 17:39:48 INFO - PROCESS | 1646 | ------- Summary: start ------- 17:39:48 INFO - PROCESS | 1646 | Number of tests: 6 17:39:48 INFO - PROCESS | 1646 | 17:39:48 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5700.18 Median:5840.88 stddev:639.81 (11.0%) stddev-sans-first:614.16 17:39:48 INFO - PROCESS | 1646 | Values: 3330.1 3341.6 3367.4 3383.9 3392.1 5582.9 5656.9 5697.5 5714.0 5718.9 5662.4 5710.0 5713.9 5716.1 5717.3 5166.8 5204.7 5227.1 5246.8 5247.1 5249.4 5262.2 5274.1 5276.6 5280.3 5164.4 5232.9 5241.1 5261.8 5265.3 5285.1 5293.1 5305.4 5315.2 5366.9 5084.4 5112.3 5151.1 5223.7 5235.6 5620.9 5635.7 5673.5 5707.4 5713.3 6196.7 6201.2 6216.1 6223.2 6225.6 6099.2 6117.6 6191.3 6209.1 6242.9 5702.5 5769.7 5784.9 5792.6 5838.7 5216.6 6243.8 6252.5 6256.2 6258.5 5978.5 6068.4 6112.3 6125.6 6134.8 6101.9 6115.7 6143.0 6160.7 6192.6 6118.9 6138.3 6220.9 6226.6 6228.9 6214.0 6222.6 6225.8 6226.6 6232.8 4821.1 4872.1 4877.7 4936.0 4945.1 3738.7 4393.2 4409.0 4412.6 4448.7 5686.3 5704.5 5708.6 5716.5 5728.4 6217.7 6275.7 6284.5 6297.0 6298.5 5597.4 5641.5 5642.2 5716.8 5717.5 4501.8 5372.4 5377.2 5380.2 5389.4 6245.8 6246.4 6265.8 6268.5 6271.3 6140.9 6189.6 6209.8 6212.1 6217.7 6271.7 6283.2 6298.5 6298.9 6318.7 6173.9 6192.8 6200.7 6205.3 6206.9 5110.7 6067.7 6255.3 6262.2 6283.9 5995.0 6009.2 6015.4 6018.0 6020.5 5996.4 6001.4 6004.1 6009.5 6011.8 5687.9 5818.7 5829.6 5836.7 5840.9 5948.3 5974.0 5984.0 6001.9 6003.0 5741.9 5851.7 5924.3 5929.1 6012.1 17:39:48 INFO - PROCESS | 1646 | 17:39:48 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:67780.07 Median:18553.52 stddev:152917.65 (824.2%) stddev-sans-first:153263.11 17:39:48 INFO - PROCESS | 1646 | Values: 470.8 518.0 522.7 524.4 549.5 30752.5 32125.4 32135.8 32168.2 32196.7 41931.4 42212.4 42656.4 45100.3 45120.0 9516.9 9677.3 9681.9 9685.2 9696.6 8598.5 9473.1 9590.7 9598.7 9602.2 10424.8 11183.9 11437.3 11700.6 11737.5 11524.3 11609.9 11612.2 11621.1 11625.0 11488.4 11564.5 11566.1 11577.8 11578.4 39251.0 39551.2 39590.8 39591.6 39597.0 3272.7 3287.8 3317.1 3332.4 3413.2 30812.0 31458.5 31513.5 31541.7 32097.7 3173.7 3189.3 3197.2 3310.4 3337.0 483095.0 484351.2 484983.0 486181.0 487091.0 32092.2 32488.0 32565.0 60046.0 60589.8 30569.5 34056.3 34388.5 34953.7 34964.0 14268.1 14414.6 14439.9 14441.1 14446.1 10420.7 11861.9 12251.9 12354.4 12433.4 7194.5 7335.7 7345.2 7606.1 7726.2 2819.6 2928.8 3213.0 3257.6 3309.6 933.8 963.7 999.6 1004.0 1056.9 29707.6 29769.9 30141.7 30404.8 30580.7 1035.7 1049.4 1058.2 1069.4 1074.0 14700.9 14975.8 15439.4 15461.1 15834.5 642086.0 652117.0 652155.0 653111.0 653175.0 49864.8 50624.2 50663.2 57968.0 58519.4 1303.1 1339.2 1349.5 1362.6 1364.7 29855.4 30310.5 30584.2 30804.8 31535.8 49811.0 50244.7 50473.5 50486.7 50537.0 412355.0 414455.0 634569.0 644553.0 654309.0 25910.7 26800.9 26990.9 41179.6 41627.4 45942.9 46728.3 47019.8 47038.0 47476.0 37744.6 37851.4 38408.3 38503.8 39357.0 29922.0 31911.9 34859.8 39279.6 39546.8 36879.0 37136.9 37772.6 37983.4 38219.4 32858.8 33770.0 34243.0 34273.8 34567.3 20693.8 22115.0 24957.5 25459.3 25557.7 17:39:48 INFO - PROCESS | 1646 | 17:39:48 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:33588.04 Median:332.16 stddev:93570.95 (28170.7%) stddev-sans-first:93800.09 17:39:48 INFO - PROCESS | 1646 | Values: 315.5 348.8 366.8 371.7 397.6 14957.4 15139.2 16122.8 20360.6 20980.7 19340.6 19993.0 20436.0 20701.6 21158.5 5752.4 6118.5 6119.3 6122.6 6127.6 4740.1 5983.3 6037.1 6045.4 6052.8 5714.2 5975.2 6002.5 6017.1 6018.3 5936.3 5961.1 6014.0 6028.4 6039.0 4972.2 5185.0 5199.4 5224.5 5233.0 21356.9 25796.0 26144.9 26194.7 26413.1 1818.5 1863.2 1978.4 2102.6 2111.4 9895.5 10024.6 10028.9 10511.2 10531.9 1533.5 1900.7 1906.4 1915.8 1940.4 98103.5 114090.0 120679.0 122203.0 123532.8 8996.6 11339.4 11487.6 11568.8 11676.6 14326.4 14352.7 14368.9 14375.8 14381.6 7258.9 7772.0 8005.8 8209.3 8488.4 7186.3 7932.8 8193.9 8367.8 8392.7 4394.8 4409.3 4460.2 4471.5 4532.0 1603.3 1833.8 1865.6 1906.7 1913.2 676.3 738.8 845.8 927.9 951.7 20903.8 21667.8 21743.6 21771.0 21823.7 725.9 887.8 898.1 949.7 969.1 10694.0 11289.9 11300.9 11307.3 11335.8 547023.0 549850.0 558092.2 568683.0 568983.0 122896.0 123560.0 126973.0 127835.0 135223.0 952.9 953.2 975.6 977.4 980.7 18357.4 18526.4 18572.0 18598.9 18606.6 36068.8 50072.7 51392.7 52443.2 52939.9 74656.3 77179.0 78656.2 78704.6 80246.6 5711.2 7776.6 7942.6 8150.8 8504.4 9143.8 13007.6 13112.3 13395.3 13492.3 10587.7 11205.9 11266.8 11516.5 11541.5 11182.6 11260.3 11414.1 11417.6 11572.1 9668.0 10695.5 11483.1 11778.3 11869.7 7993.6 8132.5 8212.6 8245.4 8385.8 11069.5 11280.6 11335.5 11376.0 11470.9 17:39:48 INFO - PROCESS | 1646 | 17:39:48 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5662.80 Median:5032.90 stddev:3188.34 (63.3%) stddev-sans-first:3179.38 17:39:48 INFO - PROCESS | 1646 | Values: 1167.9 1331.9 1485.8 1631.5 1868.9 7893.4 8412.6 8447.6 8890.7 8895.6 8785.5 8848.1 8868.0 8870.5 8910.1 2035.9 2352.8 2377.4 2380.9 2384.5 2303.2 2339.8 2363.5 2364.5 2365.0 2327.3 2354.6 2357.8 2364.6 2370.3 2302.4 2323.5 2331.9 2336.0 2336.5 2329.6 2335.6 2336.7 2336.8 2337.2 8705.7 8802.7 8807.4 8900.0 8932.9 5215.4 5422.8 5429.0 5429.0 5430.0 3612.3 3646.9 3690.9 3703.6 3706.1 1068.2 1080.1 1081.9 1095.8 1101.5 11733.8 11734.2 11849.0 11852.2 11888.3 4360.5 4496.3 4615.5 4626.7 4628.3 9126.1 9146.5 9150.0 9160.3 9164.3 5253.1 5257.2 5262.2 5267.0 5267.9 4238.2 4417.9 4457.7 4574.5 4917.4 3086.9 3732.4 3776.0 3779.0 3809.6 1141.1 1142.7 1143.8 1151.4 1157.8 857.5 873.6 883.5 897.2 897.3 5805.8 5923.7 5972.8 6119.9 6338.1 799.8 908.2 931.1 979.5 986.9 3777.3 3980.4 3982.3 4015.8 4076.6 10183.5 10774.6 10942.7 10976.4 11001.3 10613.2 10755.9 10876.2 10879.2 10890.8 7997.0 8236.9 8249.4 8262.7 8273.5 5782.9 5786.3 5865.4 5940.4 6000.7 9245.8 9260.9 9991.9 10048.3 10054.2 9250.9 9618.4 10034.0 10054.1 10207.8 6951.8 7245.1 7441.5 7542.6 7614.4 8225.7 8227.3 8229.1 8309.9 8324.9 7460.4 7483.9 7531.1 7539.4 7552.2 7169.1 7228.9 7229.8 7281.7 7451.6 6866.5 6929.8 7018.9 7026.6 7032.7 4766.1 4887.3 4977.1 5011.4 5178.8 4345.8 5001.7 5054.4 5094.6 5124.3 17:39:48 INFO - PROCESS | 1646 | 17:39:48 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4442.49 Median:4903.90 stddev:1372.51 (28.0%) stddev-sans-first:1340.57 17:39:48 INFO - PROCESS | 1646 | Values: 293.7 294.3 304.0 307.2 310.9 4300.6 5220.9 5259.2 5308.1 5338.7 3978.5 4892.7 5089.8 5141.5 5152.4 3296.4 3986.5 3987.3 3997.8 4002.6 3443.3 3457.7 3991.3 4020.3 4035.9 3447.9 3897.0 3936.6 3972.2 3991.3 3096.7 3271.5 3959.4 3966.8 3989.7 3491.1 3502.7 3520.9 3527.9 3532.1 4629.3 4858.6 5089.9 5099.5 5129.3 3913.9 4168.5 4421.2 4483.2 4555.7 5023.4 5436.3 5441.2 5443.9 5455.5 3828.6 3829.8 3830.1 3835.1 3865.4 5525.8 5653.3 5698.6 5703.7 5704.5 3253.9 3287.0 3856.3 3884.5 3894.5 3491.7 3967.4 3978.9 3980.8 3985.6 3767.0 4384.8 4513.1 4563.2 4591.5 5465.0 5482.5 5488.9 5491.9 5524.4 1256.7 1260.9 1288.1 1330.2 1366.8 870.9 892.3 914.9 941.2 945.4 4573.7 5016.7 5209.6 5217.3 5236.4 4852.3 4881.7 5136.3 5533.4 5688.7 4672.4 4761.9 4958.8 5192.5 5211.2 2698.0 2938.9 2967.7 2970.3 2974.4 4905.9 5642.7 5668.3 5786.9 5838.0 4796.4 4808.6 5791.9 5797.7 5823.6 4644.1 5847.7 5889.9 5908.5 5925.4 4682.5 4902.0 5661.3 5662.3 5699.4 5600.4 5625.4 5640.5 5650.0 5655.8 5843.9 5850.2 5880.6 5905.5 5993.2 3524.5 3543.8 3724.3 3922.5 3981.3 5557.9 5711.7 5726.1 5763.1 5781.3 4897.6 5597.3 5607.0 5632.5 5645.5 4860.0 4995.0 5179.1 5316.3 5549.9 4267.3 5199.8 5431.0 5457.6 5464.2 5406.4 5443.8 5473.3 5476.5 5502.7 4572.4 4644.6 4733.8 5476.8 5500.0 17:39:48 INFO - PROCESS | 1646 | 17:39:48 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:22433.47 Median:4165.42 stddev:33922.61 (814.4%) stddev-sans-first:33978.45 17:39:48 INFO - PROCESS | 1646 | Values: 680.4 698.2 713.3 749.4 764.6 14811.0 15181.5 15383.8 15399.8 15521.7 13805.2 13919.1 13924.1 13936.2 13967.5 734.9 813.8 821.3 848.4 859.4 860.6 860.8 860.8 861.2 862.0 905.4 906.6 906.8 907.0 908.0 769.4 899.7 900.2 900.9 902.5 666.5 667.2 668.3 668.9 670.7 15095.2 15115.3 15132.2 15135.2 15142.5 13500.4 13571.7 13711.0 13804.7 14023.6 11488.8 11510.6 11533.0 11706.0 12180.1 3644.0 3659.9 3727.8 3789.2 3801.1 106447.0 115981.4 117124.4 119448.0 121379.4 10307.8 10386.3 10472.4 10638.5 10856.3 22015.2 22030.6 22038.8 22070.4 22085.0 7327.6 7351.2 7502.1 7532.7 7560.1 4069.0 4122.9 4161.5 4169.3 4232.2 3623.3 3707.1 3735.5 3737.2 3741.4 619.3 624.8 629.3 641.2 642.4 534.7 598.8 614.5 630.3 646.1 8967.4 9233.7 9305.5 9482.4 9553.4 551.7 554.5 562.4 641.3 652.6 4194.9 4328.0 4567.3 4640.7 4708.6 86597.0 86750.0 87346.6 128720.0 141512.0 72439.6 78508.6 91918.6 118171.0 118762.2 89392.1 90337.0 95283.0 104076.5 104398.0 8382.5 9710.4 9727.6 9729.2 9773.6 58245.0 60027.7 61943.8 63134.0 64641.0 79942.6 90250.5 91297.6 92249.5 96503.0 12930.4 12942.7 13049.5 13075.5 13317.7 14132.6 14478.9 14515.6 14563.7 15207.0 13339.1 13388.9 13735.7 14026.2 14204.6 13424.3 13552.0 13937.8 14048.9 14263.8 11084.3 13049.3 13592.8 13822.7 14019.1 9980.0 10942.0 11152.3 11271.0 11366.9 9293.9 9650.5 10460.5 11503.8 11589.8 17:39:48 INFO - PROCESS | 1646 | -------- Summary: end -------- 17:39:48 INFO - PROCESS | 1646 | 17:39:48 INFO - TEST-INFO | 1646: exit 0 17:39:48 INFO - TEST-OK | dromaeo_css | took 1120193ms 17:39:48 INFO - TEST-START | kraken 17:39:48 INFO - operating with platform_type : mac_ 17:39:48 INFO - Initialising browser for kraken test... 17:39:48 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpo7GO2T/profile http://localhost:49266/getInfo.html) 17:39:51 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 17:39:51 INFO - PROCESS | 1653 | colorDepth:24 17:39:51 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 17:39:51 INFO - PROCESS | 1653 | __metrics 17:39:51 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" 17:39:51 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] 17:39:51 INFO - TEST-INFO | 1653: exit 0 17:39:51 INFO - Browser initialized. 17:39:51 INFO - Running cycle 1/1 for kraken test... 17:39:51 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpo7GO2T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:40:03 INFO - PROCESS | 1654 | RSS: Main: 235278336 17:40:03 INFO - PROCESS | 1654 | 17:40:06 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 17:40:07 INFO - PROCESS | 1654 | RSS: Main: 293720064 17:40:07 INFO - PROCESS | 1654 | 17:40:10 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 17:40:10 INFO - PROCESS | 1654 | RSS: Main: 293441536 17:40:10 INFO - PROCESS | 1654 | 17:40:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 17:40:15 INFO - PROCESS | 1654 | RSS: Main: 384638976 17:40:15 INFO - PROCESS | 1654 | 17:40:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 17:40:17 INFO - PROCESS | 1654 | RSS: Main: 284241920 17:40:17 INFO - PROCESS | 1654 | 17:40:19 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:40:20 INFO - PROCESS | 1654 | RSS: Main: 289689600 17:40:20 INFO - PROCESS | 1654 | 17:40:23 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 17:40:23 INFO - PROCESS | 1654 | RSS: Main: 326324224 17:40:23 INFO - PROCESS | 1654 | 17:40:26 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 17:40:27 INFO - PROCESS | 1654 | RSS: Main: 328577024 17:40:27 INFO - PROCESS | 1654 | 17:40:30 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 17:40:30 INFO - PROCESS | 1654 | RSS: Main: 324665344 17:40:30 INFO - PROCESS | 1654 | 17:40:32 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:40:32 INFO - PROCESS | 1654 | RSS: Main: 300752896 17:40:32 INFO - PROCESS | 1654 | 17:40:34 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:40:35 INFO - PROCESS | 1654 | RSS: Main: 305065984 17:40:35 INFO - PROCESS | 1654 | 17:40:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:40:38 INFO - PROCESS | 1654 | RSS: Main: 311037952 17:40:38 INFO - PROCESS | 1654 | 17:40:41 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:40:42 INFO - PROCESS | 1654 | RSS: Main: 321449984 17:40:42 INFO - PROCESS | 1654 | 17:40:45 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:40:45 INFO - PROCESS | 1654 | RSS: Main: 321396736 17:40:45 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 17:40:47 INFO - PROCESS | 1654 | RSS: Main: 365674496 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | __start_tp_report 17:40:47 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 17:40:47 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 17:40:47 INFO - PROCESS | 1654 | |i|pagename|runs| 17:40:47 INFO - PROCESS | 1654 | |0;ai-astar;113;111;112;112;112;111;112;111;112;112 17:40:47 INFO - PROCESS | 1654 | |1;audio-beat-detection;149;115;112;150;115;121;140;139;164;113 17:40:47 INFO - PROCESS | 1654 | |2;audio-dft;184;183;183;181;184;183;222;180;181;182 17:40:47 INFO - PROCESS | 1654 | |3;audio-fft;81;59;59;87;60;61;59;58;82;58 17:40:47 INFO - PROCESS | 1654 | |4;audio-oscillator;92;73;90;70;70;74;126;69;69;95 17:40:47 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;116;128;82;112;131;91;89;86;89;87 17:40:47 INFO - PROCESS | 1654 | |6;imaging-darkroom;95;93;95;95;94;95;96;95;95;98 17:40:47 INFO - PROCESS | 1654 | |7;imaging-desaturate;134;138;97;86;84;86;85;86;85;87 17:40:47 INFO - PROCESS | 1654 | |8;json-parse-financial;59;55;56;56;56;55;56;55;56;55 17:40:47 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;71;70;65;75;74;67;70;69;70;69 17:40:47 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;86;90;84;86;84;89;88;89;97;108 17:40:47 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;146;164;197;158;158;143;145;143;179;132 17:40:47 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;171;169;176;172;173;169;172;170;174;168 17:40:47 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;71;70;69;69;68;67;70;68;69;68 17:40:47 INFO - PROCESS | 1654 | __end_tp_report 17:40:47 INFO - PROCESS | 1654 | __start_cc_report 17:40:47 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,2116 17:40:47 INFO - PROCESS | 1654 | __end_cc_report 17:40:47 INFO - PROCESS | 1654 | __startTimestamp1476319247738__endTimestamp 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | ------- Summary: start ------- 17:40:47 INFO - PROCESS | 1654 | Number of tests: 14 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:0.63 (0.6%) stddev-sans-first:0.50 17:40:47 INFO - PROCESS | 1654 | Values: 113.0 111.0 112.0 112.0 112.0 111.0 112.0 111.0 112.0 112.0 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:131.80 Median:139.50 stddev:18.88 (13.5%) stddev-sans-first:18.98 17:40:47 INFO - PROCESS | 1654 | Values: 149.0 115.0 112.0 150.0 115.0 121.0 140.0 139.0 164.0 113.0 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:186.30 Median:183.00 stddev:12.61 (6.9%) stddev-sans-first:13.35 17:40:47 INFO - PROCESS | 1654 | Values: 184.0 183.0 183.0 181.0 184.0 183.0 222.0 180.0 181.0 182.0 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:66.40 Median:60.50 stddev:11.82 (19.5%) stddev-sans-first:11.29 17:40:47 INFO - PROCESS | 1654 | Values: 81.0 59.0 59.0 87.0 60.0 61.0 59.0 58.0 82.0 58.0 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:82.80 Median:73.50 stddev:18.36 (25.0%) stddev-sans-first:19.17 17:40:47 INFO - PROCESS | 1654 | Values: 92.0 73.0 90.0 70.0 70.0 74.0 126.0 69.0 69.0 95.0 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:101.10 Median:86.50 stddev:18.69 (21.6%) stddev-sans-first:19.03 17:40:47 INFO - PROCESS | 1654 | Values: 116.0 128.0 82.0 112.0 131.0 91.0 89.0 86.0 89.0 87.0 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:95.00 stddev:1.29 (1.4%) stddev-sans-first:1.36 17:40:47 INFO - PROCESS | 1654 | Values: 95.0 93.0 95.0 95.0 94.0 95.0 96.0 95.0 95.0 98.0 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:86.00 stddev:21.00 (24.4%) stddev-sans-first:17.44 17:40:47 INFO - PROCESS | 1654 | Values: 134.0 138.0 97.0 86.0 84.0 86.0 85.0 86.0 85.0 87.0 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:1.20 (2.1%) stddev-sans-first:0.53 17:40:47 INFO - PROCESS | 1654 | Values: 59.0 55.0 56.0 56.0 56.0 55.0 56.0 55.0 56.0 55.0 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.00 stddev:2.94 (4.2%) stddev-sans-first:3.10 17:40:47 INFO - PROCESS | 1654 | Values: 71.0 70.0 65.0 75.0 74.0 67.0 70.0 69.0 70.0 69.0 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:88.50 stddev:7.32 (8.3%) stddev-sans-first:7.62 17:40:47 INFO - PROCESS | 1654 | Values: 86.0 90.0 84.0 86.0 84.0 89.0 88.0 89.0 97.0 108.0 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:156.50 Median:158.00 stddev:19.48 (12.3%) stddev-sans-first:20.29 17:40:47 INFO - PROCESS | 1654 | Values: 146.0 164.0 197.0 158.0 158.0 143.0 145.0 143.0 179.0 132.0 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.40 Median:172.00 stddev:2.50 (1.5%) stddev-sans-first:2.65 17:40:47 INFO - PROCESS | 1654 | Values: 171.0 169.0 176.0 172.0 173.0 169.0 172.0 170.0 174.0 168.0 17:40:47 INFO - PROCESS | 1654 | 17:40:47 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:1.20 (1.7%) stddev-sans-first:1.00 17:40:47 INFO - PROCESS | 1654 | Values: 71.0 70.0 69.0 69.0 68.0 67.0 70.0 68.0 69.0 68.0 17:40:47 INFO - PROCESS | 1654 | -------- Summary: end -------- 17:40:47 INFO - PROCESS | 1654 | 17:40:48 INFO - TEST-INFO | 1654: exit 0 17:40:48 INFO - TEST-OK | kraken | took 59724ms 17:40:48 INFO - SUITE-END | took 1179s 17:40:48 INFO - Completed test suite (00:19:40) 17:40:48 INFO - javascript benchmark 17:40:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.067581077298, 3341.5655964724588, 3367.360201561703, 3383.9323213535713, 3392.0841372829336, 5582.888342233153, 5656.91514627281, 5697.515711164551, 5714.0, 5718.942810571898, 5662.433756624336, 5709.971450142754, 5713.857153571163, 5716.056850619653, 5717.313922329316, 5166.793328266865, 5204.687718736869, 5227.111391063529, 5246.790128394879, 5247.055530004598, 5249.396319423258, 5262.158054711247, 5274.129768588197, 5276.604254680895, 5280.339957505312, 5164.354455693038, 5232.92150617741, 5241.109011468059, 5261.815836445725, 5265.3155089838265, 5285.101532739443, 5293.126634105368, 5305.363356397218, 5315.176147697113, 5366.91949620756, 5084.389873215201, 5112.335396398445, 5151.072806894757, 5223.660462069954, 5235.58115350771, 5620.887582248373, 5635.7463914123555, 5673.489385955284, 5707.400722924116, 5713.3429655589725, 6196.659183744892, 6201.193844800147, 6216.129741836146, 6223.222097237845, 6225.564210505267, 6099.176611157482, 6117.644716737907, 6191.318954914956, 6209.130721698965, 6242.875142497124, 5702.515286200636, 5769.711514424262, 5784.855378615484, 5792.623479473821, 5838.678872662044, 5216.58267338612, 6243.7814676486105, 6252.531060170414, 6256.217972753406, 6258.499320054385, 5978.521718262528, 6068.362821903636, 6112.266528016667, 6125.601835880653, 6134.7852825151795, 6101.933984285782, 6115.696722738819, 6142.969285153546, 6160.661163635953, 6192.59748116371, 6118.893302669218, 6138.32709113608, 6220.937790622126, 6226.62640241587, 6228.937710622836, 6213.968930155411, 6222.564420490522, 6225.782097626561, 6226.59527130735, 6232.844178895564, 4821.059893320839, 4872.123017856749, 4877.6829506081995, 4936.03747269279, 4945.134601444733, 3738.719596030309, 4393.231184542692, 4409.0, 4412.602865742035, 4448.688591798607, 5686.289213848269, 5704.543636509069, 5708.628939119027, 5716.5140963017775, 5728.398518155617, 6217.720202590895, 6275.654838983808, 6284.497240220771, 6296.968515157487, 6298.464630506367, 5597.440255974451, 5641.492265696107, 5642.181883626851, 5716.79991200306, 5717.456841600041, 4501.7974191161475, 5372.409035006146, 5377.247185394048, 5380.192971054373, 5389.353277606712, 6245.750169993149, 6246.406591373812, 6265.780697675559, 6268.529860260407, 6271.341509141565, 6140.9078863816185, 6189.566730328834, 6209.751609935733, 6212.099245609271, 6217.689115544114, 6271.749130034747, 6283.183186185766, 6298.496120310456, 6298.905516417349, 6318.747250109944, 6173.876522469615, 6192.752289908353, 6200.689965501797, 6205.348438414082, 6206.906896396648, 5110.66780659256, 6067.696615169312, 6255.311915689362, 6262.185915830913, 6283.937160628335, 5995.010823214296, 6009.188759517409, 6015.39846015395, 6017.969910150421, 6020.518358531394, 5996.370381110095, 6001.36985616504, 6004.099385092097, 6009.54928380382, 6011.819645410645, 5687.914681279702, 5818.709064546841, 5829.621074630136, 5836.678982655994, 5840.883182336244, 5948.256467941507, 5973.970130149321, 5984.012637914695, 6001.879962400815, 6003.0, 5741.913871292018, 5851.707414629337, 5924.31870334917, 5929.140274660236, 6012.128241405059], "value": 5659.447517706868, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.8469747332117, 517.984460466186, 522.7281813457, 524.446708722298, 549.52740643047, 30752.53871191931, 32125.35749285013, 32135.839320803363, 32168.195795105134, 32196.67803321966, 41931.37102943451, 42212.36681449773, 42656.36015459763, 45100.32349514752, 45120.0, 9516.85724714128, 9677.322587418883, 9681.854772178423, 9685.176759975411, 9696.612135514571, 8598.527081010541, 9473.100055494717, 9590.664326748565, 9598.712038638852, 9602.231821454301, 10424.79150416995, 11183.888161118406, 11437.256578322424, 11700.648980530597, 11737.471813768358, 11524.308541487537, 11609.883901160965, 11612.18714689972, 11621.128415368881, 11625.0, 11488.36813975231, 11564.479598418091, 11566.132540059445, 11577.826332605018, 11578.363190024545, 39251.0, 39551.20897582061, 39590.802045989876, 39591.60408395908, 39597.0, 3272.656371081026, 3287.7698561100733, 3317.137544238491, 3332.3668502984597, 3413.2320227948762, 30812.0, 31458.528122078187, 31513.52729709056, 31541.684583154332, 32097.6790232097, 3173.698498642625, 3189.3142974260413, 3197.2326641605846, 3310.4041272570926, 3337.0489410517885, 483095.0, 484351.1564884341, 484983.0, 486180.99999999645, 487091.0, 32092.19769505781, 32488.0, 32565.02304930856, 60046.0, 60589.788204235665, 30569.47152642359, 34056.31887362239, 34388.48417273743, 34953.65046349504, 34964.0, 14268.14391136535, 14414.567562973127, 14439.92780036093, 14441.133531988115, 14446.061006034573, 10420.68737937863, 11861.881381186251, 12251.93874030642, 12354.38228088592, 12433.37833108359, 7194.496385253086, 7335.669894854745, 7345.1553071397475, 7606.125295590967, 7726.150123486412, 2819.5629677400034, 2928.780341474398, 3212.951805722916, 3257.609086909586, 3309.6028476582483, 933.8225737109927, 963.6868017894212, 999.6301368493704, 1003.9749006274756, 1056.9471526423647, 29707.554386684216, 29769.85115074454, 30141.698583014328, 30404.847975760422, 30580.69419305823, 1035.746242170673, 1049.3808652894677, 1058.2116323339162, 1069.4332004037872, 1074.0, 14700.926495367668, 14975.775363369557, 15439.382424703112, 15461.149636770086, 15834.524964251092, 642086.0, 652117.0, 652155.0, 653111.0, 653175.0, 49864.75067624639, 50624.24063639048, 50663.24005139926, 57968.0, 58519.4148058514, 1303.1008604063165, 1339.169714776825, 1349.473705254971, 1362.6389006913175, 1364.7202323523654, 29855.402891941605, 30310.545341820332, 30584.235394114436, 30804.84597576998, 31535.842320788248, 49811.0, 50244.74877625588, 50473.49526504688, 50486.74756626193, 50537.0, 412355.0, 414455.0, 634569.0, 644553.0, 654309.0, 25910.74089259083, 26800.927962881266, 26990.865045674647, 41179.58820411757, 41627.375589365576, 45942.85142871455, 46728.29907551457, 47019.76490117527, 47038.0, 47476.0, 37744.62255377411, 37851.432228516045, 38408.33888132613, 38503.8448846535, 39357.0, 29922.0, 31911.883084091944, 34859.82570087133, 39279.6072039276, 39546.813595592175, 36879.0, 37136.88589342324, 37772.62227377691, 37983.43024854684, 38219.42670859884, 32858.835705821315, 33770.0, 34243.0, 34273.82863085669, 34567.308653827284, 20693.793062069788, 22115.0, 24957.500849983262, 25459.339784571606, 25557.74442255628], "value": 18535.14993623439, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.52198419394614, 348.7924684812536, 366.8019269594421, 371.6655010490558, 397.58055251709436, 14957.401703931844, 15139.167345795977, 16122.758158627616, 20360.59278814423, 20980.685289720666, 19340.61318773624, 19993.000349982478, 20436.0, 20701.585968280626, 21158.471038224052, 5752.36723960364, 6118.510519158456, 6119.265688117433, 6122.63264204147, 6127.632342059468, 4740.123077230706, 5983.252093488314, 6037.094435834616, 6045.365236650155, 6052.788152414666, 5714.228579141804, 5975.163477113208, 6002.459778619924, 6017.06735456005, 6018.307894592134, 5936.347001829795, 5961.1058341248945, 6014.0, 6028.367021462771, 6038.969805150946, 4972.229304457779, 5184.9740751296, 5199.376074871002, 5224.4775522447835, 5233.0, 21356.893215533823, 25796.0, 26144.86927565369, 26194.73805261942, 26413.113573988874, 1818.4635532517807, 1863.2081365419642, 1978.357033963953, 2102.6425507663735, 2111.4193596761015, 9895.466202738588, 10024.649137280234, 10028.89971100287, 10511.1591072714, 10531.894681053245, 1533.5476034569829, 1900.695888657822, 1906.4090132059107, 1915.7796853361763, 1940.3790786948182, 98103.50948245214, 114090.0, 120679.0, 122203.0, 123532.76467235392, 8996.640134394682, 11339.376334301527, 11487.597934072268, 11568.826467602992, 11676.649700508999, 14326.426942922166, 14352.712945741236, 14368.928155359155, 14375.78436323456, 14381.568552943429, 7258.911163325438, 7771.950786643731, 8005.8398832024195, 8209.302209312274, 8488.363372746946, 7186.281371862772, 7932.801679957932, 8193.877091843628, 8367.790805229917, 8392.706255281037, 4394.780260986938, 4409.316555933835, 4460.219461594208, 4471.530489298641, 4531.977340113278, 1603.3346161343145, 1833.7805359436093, 1865.552267455828, 1906.6949288113835, 1913.2442685139292, 676.3439463720183, 738.8411491529347, 845.7758693946109, 927.9164875161268, 951.695457453622, 20903.79096209049, 21667.78332216689, 21743.565128697337, 21771.0, 21823.67264491034, 725.8693435181718, 887.8490656588395, 898.1018981018981, 949.715085474355, 969.0987381734914, 10694.0, 11289.887101128883, 11300.886991130148, 11307.321560706385, 11335.773284534263, 547023.0, 549850.0, 558092.2095389497, 568683.0, 568983.0, 122896.0, 123560.0, 126973.0, 127835.0, 135223.0, 952.9285303602257, 953.1612181280428, 975.6243846119295, 977.3793641037992, 980.6518685866557, 18357.3574924877, 18526.444206673823, 18572.0, 18598.907005464887, 18606.627867442305, 36068.819655901556, 50072.74963625159, 51392.74303628458, 52443.213351800514, 52939.94120117653, 74656.25343746493, 77179.0, 78656.21343786489, 78704.60647696725, 80246.5987670058, 5711.171880077282, 7776.572288524186, 7942.6028698566, 8150.75547733569, 8504.44721093127, 9143.817123657622, 13007.609771706864, 13112.344382781013, 13395.263260520765, 13492.257925814174, 10587.735306617087, 11205.943970280096, 11266.830997535208, 11516.481758321062, 11541.480633371351, 11182.608608698656, 11260.268082574607, 11414.143939204427, 11417.646534388428, 11572.074234061087, 9667.984861589432, 10695.5187016586, 11483.138764592855, 11778.293302401884, 11869.703257418634, 7993.600319984093, 8132.471389359673, 8212.589370531568, 8245.381596380415, 8385.832283354177, 11069.501872415922, 11280.554397251828, 11335.489902954221, 11375.976162145447, 11470.942645286721], "value": 9315.207660792646, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1167.9474423650936, 1331.9267440290782, 1485.836557978622, 1631.5350125214316, 1868.9160287033521, 7893.447458677888, 8412.621432035558, 8447.619857106421, 8890.733278001655, 8895.644174233039, 8785.516796576201, 8848.070952549986, 8868.02451730309, 8870.512121833297, 8910.108989101087, 2035.8778473291575, 2352.7882490575844, 2377.405648587853, 2380.9166679166233, 2384.463495713468, 2303.239930822833, 2339.7660233976553, 2363.5391098735754, 2364.538914911593, 2364.988175059122, 2327.3483424641045, 2354.5997180479358, 2357.7995870350956, 2364.5861974154454, 2370.3481542575755, 2302.4474126209757, 2323.5120624668757, 2331.860088394702, 2335.976640233593, 2336.4859730859184, 2329.6039673255586, 2335.5679199348174, 2336.707911511061, 2336.778006089419, 2337.1586228957563, 8705.651773929036, 8802.691905783331, 8807.383483156182, 8900.0, 8932.8660070099, 5215.400228973679, 5422.810201642924, 5429.0, 5429.0227759003365, 5429.972850135763, 3612.295602357541, 3646.872359467406, 3690.8708195213308, 3703.6111208323277, 3706.0549559862334, 1068.2362111090608, 1080.0981180714114, 1081.8918108189198, 1095.8411030400628, 1101.5208384352832, 11733.823992640117, 11734.178607497479, 11849.0, 11852.170348075639, 11888.286702797946, 4360.4767427908855, 4496.258117410591, 4615.469221039623, 4626.745528995937, 4628.282616194495, 9126.087391260953, 9146.496942668084, 9149.954250228839, 9160.267178625827, 9164.312676549285, 5253.080710875582, 5257.237700533401, 5262.210668399694, 5266.973665131726, 5267.868303292448, 4238.19474299882, 4417.889552761207, 4457.68796184264, 4574.45106587205, 4917.385326834145, 3086.861091250899, 3732.4401339798706, 3775.962240377616, 3778.981105094457, 3809.6190380962234, 1141.0928311991986, 1142.6914733022038, 1143.8284257361463, 1151.401271338899, 1157.8494795676509, 857.5069335132324, 873.5675840459013, 883.5361435246547, 897.1701176411793, 897.2552781191596, 5805.796797112081, 5923.703814809242, 5972.820815375546, 6119.898418284752, 6338.112664227012, 799.7960520067462, 908.2053203446984, 931.1061381074245, 979.5004547680734, 986.9062439068277, 3777.263433630416, 3980.383040628707, 3982.3230050891416, 4015.8393664253686, 4076.571959944163, 10183.490825458697, 10774.569017239379, 10942.671719848417, 10976.396298203515, 11001.339919604849, 10613.204009699304, 10755.89244107549, 10876.238663293494, 10879.184061195441, 10890.836637450444, 7996.960015199887, 8236.876446853183, 8249.432607804498, 8262.669493220325, 8273.46222495536, 5782.913256301243, 5786.276715410574, 5865.442782935615, 5940.405959404025, 6000.669963152069, 9245.844269466317, 9260.907390926004, 9991.90089090214, 10048.296619236584, 10054.19566434669, 9250.907490925005, 9618.422894626345, 10034.0, 10054.095131437913, 10207.795844083224, 6951.826204344932, 7245.130584329914, 7441.479096463196, 7542.622868856645, 7614.352780013761, 8225.670973161246, 8227.341812654853, 8229.053658829072, 8309.875351869849, 8324.916750832414, 7460.3658689012045, 7483.877418387286, 7531.096268447822, 7539.396848252236, 7552.169261381134, 7169.139703235645, 7228.891566626399, 7229.758075793705, 7281.745138920112, 7451.552906825391, 6866.519343645897, 6929.792106236821, 7018.894716579358, 7026.648667566704, 7032.718691252293, 4766.094442055998, 4887.34020907173, 4977.129002424634, 5011.423686275979, 5178.792848286026, 4345.847895323648, 5001.674891132065, 5054.368203974504, 5094.617903657167, 5124.30821839047], "value": 4533.301476114744, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.7473772555602, 294.3069072334652, 303.9726424621784, 307.20740489536996, 310.9362580670963, 4300.612944834964, 5220.895582088361, 5259.184826351921, 5308.071087559676, 5338.652987555806, 3978.508059477696, 4892.730899800509, 5089.821856235032, 5141.460146684601, 5152.355955505562, 3296.4066468035744, 3986.5415477220054, 3987.262356464049, 3997.8400863965553, 4002.5597184309704, 3443.311337732451, 3457.6715212054814, 3991.2815693175216, 4020.336644453662, 4035.878923632296, 3447.931041379171, 3896.9817494293743, 3936.626020528045, 3972.205558888234, 3991.3015222335976, 3096.714863331726, 3271.460209065501, 3959.4456776051375, 3966.841326346943, 3989.68082553395, 3491.0923159978333, 3502.719782417426, 3520.9295814083575, 3527.8588856445713, 3532.116970757311, 4629.310301739874, 4858.562729354375, 5089.898202035975, 5099.464556221617, 5129.307543481619, 3913.863014794497, 4168.54101064628, 4421.170450911419, 4483.237849565582, 4555.681102322838, 5023.372078490189, 5436.320459942507, 5441.238226648273, 5443.945560544423, 5455.536279416255, 3828.617138286177, 3829.80850957451, 3830.080780612661, 3835.137094153821, 3865.4201869719486, 5525.751341189656, 5653.265075540154, 5698.572607054488, 5703.686297253649, 5704.543636509069, 3253.9099401700696, 3286.9810358788372, 3856.2865869814173, 3884.533855937306, 3894.4937158169823, 3491.6682915123024, 3967.3652215645357, 3978.920421591552, 3980.8057582725646, 3985.5815139410524, 3767.0017445377, 4384.81609965314, 4513.097380523908, 4563.224251877189, 4591.494935557086, 5465.0, 5482.533984611273, 5488.862778430571, 5491.885624034775, 5524.4199359067525, 1256.7486502699496, 1260.8676089010705, 1288.1240756285629, 1330.2018788726687, 1366.8223130992908, 870.9259712924346, 892.2727976698969, 914.9085091490931, 941.152962333905, 945.413843417072, 4573.719358579603, 5016.698998060128, 5209.609279304067, 5217.321748172789, 5236.371635403776, 4852.344933434012, 4881.731504767201, 5136.306598609214, 5533.363663178786, 5688.744006519716, 4672.415948006499, 4761.857144285677, 4958.801647934114, 5192.506711862367, 5211.202135263328, 2697.959530607003, 2938.9265268368463, 2967.679382674689, 2970.287131088523, 2974.419988102299, 4905.852824415273, 5642.689652069093, 5668.26312579362, 5786.860480565295, 5837.970810145922, 4796.448408433075, 4808.591269742041, 5791.942080579224, 5797.681127538026, 5823.56323275756, 4644.071185762793, 5847.72527384666, 5889.852753681193, 5908.468237858613, 5925.377835327315, 4682.484926657997, 4901.950980490149, 5661.320641523043, 5662.329612764162, 5699.430056994267, 5600.43995600453, 5625.353084395183, 5640.520555752807, 5650.0, 5655.830325090254, 5843.853903652443, 5850.180974663635, 5880.588358814842, 5905.498032667271, 5993.160957465871, 3524.54180956474, 3543.81282270432, 3724.2551489701627, 3922.5489068757456, 3981.2833689936087, 5557.944420555905, 5711.657300561979, 5726.134858970262, 5763.135529670583, 5781.335146458043, 4897.632677549127, 5597.3003374578175, 5606.971965140148, 5632.464915832989, 5645.548356131581, 4859.979404325131, 4994.976029913932, 5179.1195496766395, 5316.282301889196, 5549.916751248654, 4267.338562522814, 5199.818006369759, 5430.972845135749, 5457.590680698884, 5464.23500709893, 5406.431944826333, 5443.822471011627, 5473.343198816167, 5476.507114359727, 5502.669839809624, 4572.4055872736335, 4644.58198762113, 4733.83431579893, 5476.80831170923, 5499.972500137474], "value": 4011.5774779622698, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.3570625758657, 698.1656919980625, 713.3294702979196, 749.4117118062319, 764.6198611506236, 14811.0, 15181.468648597302, 15383.846161538382, 15399.846001540009, 15521.689566208683, 13805.1716896986, 13919.095258808196, 13924.094933829294, 13936.233507157101, 13967.511137110203, 734.8603765284605, 813.8453693798174, 821.2977903892175, 848.4018766769443, 859.4284800607588, 860.6170254236868, 860.7805009722512, 860.8407444622734, 861.1732736572898, 862.017300277684, 905.4295793650024, 906.6146887572755, 906.8186362727448, 906.9637214511412, 907.9727608171765, 769.3844924060785, 899.7030979776658, 900.1628485508475, 900.8873890763655, 902.4810733828042, 666.4701562258017, 667.1926968368281, 668.2582333609689, 668.8729141463156, 670.661316035399, 15095.24523773818, 15115.319810608438, 15132.167730774803, 15135.167565783877, 15142.470013549582, 13500.392482338319, 13571.728565428637, 13711.02380309709, 13804.654883627889, 14023.579292621238, 11488.827667584992, 11510.597129100524, 11533.0, 11706.004989575897, 12180.086493513021, 3643.9635603643887, 3659.9268014639824, 3727.7949712765785, 3789.1663833956486, 3801.144742432958, 106447.0, 115981.42009289899, 117124.41437792842, 119448.0, 121379.3931030348, 10307.793844123225, 10386.324888882198, 10472.424016679004, 10638.521266543024, 10856.294340867818, 22015.22946696858, 22030.559388812133, 22038.779612203674, 22070.448238793837, 22085.0, 7327.633618319062, 7351.154617218981, 7502.099748030271, 7532.698692052366, 7560.130584982688, 4068.959310406858, 4122.9381559276635, 4161.542230354658, 4169.291220492523, 4232.195882782262, 3623.2753449310244, 3707.147356108056, 3735.477033215352, 3737.2151848111657, 3741.401375779862, 619.3434958943444, 624.8125562331282, 629.3423372575669, 641.2337256977867, 642.4025656139835, 534.7085838218103, 598.7634884220708, 614.4715544631613, 630.3098107572201, 646.1341801985362, 8967.372283940193, 9233.676821311223, 9305.451829723674, 9482.431054136774, 9553.379030363005, 551.6745120378996, 554.5369616370376, 562.3701454371048, 641.2785616181795, 652.6149571752688, 4194.874153775391, 4327.9716126584735, 4567.3377360282575, 4640.6751527393435, 4708.623310135181, 86597.0, 86750.0, 87346.56326718326, 128720.0, 141512.0, 72439.63780181065, 78508.61625064333, 91918.62122068174, 118171.0, 118762.21856672157, 89392.10607893838, 90337.0, 95283.0, 104076.47961760142, 104398.0, 8382.497050176766, 9710.417374957526, 9727.610895564098, 9729.173020293354, 9773.560189791619, 58244.96142634987, 60027.69986150216, 61943.76112477635, 63134.0, 64641.0, 79942.60028700052, 90250.54874725585, 91297.63053554334, 92249.53875230582, 96503.0, 12930.353482325658, 12942.676433089247, 13049.478020879058, 13075.542356017493, 13317.667058323232, 14132.646683832987, 14478.855211447752, 14515.564533064025, 14563.708725825636, 15207.0, 13339.132956357806, 13388.866111338762, 13735.725285494435, 14026.150111409741, 14204.644883877985, 13424.328783560979, 13552.0, 13937.790933135808, 14048.929755351157, 14263.786043209568, 11084.279521831055, 13049.34753262352, 13592.796108058585, 13822.723545529234, 14019.088759230617, 9979.950100249453, 10942.0, 11152.330860148093, 11271.0, 11366.886331136582, 9293.86059209126, 9650.469224192737, 10460.529276182438, 11503.827442588536, 11589.82615260755], "value": 7562.83080684692, "unit": "score"}], "name": "dromaeo_css", "value": 7157.095944390701, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 112.0, 112.0, 112.0, 111.0, 112.0, 111.0, 112.0, 112.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 112.0, 150.0, 115.0, 121.0, 140.0, 139.0, 164.0, 113.0], "value": 131.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 183.0, 181.0, 184.0, 183.0, 222.0, 180.0, 181.0, 182.0], "value": 186.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 87.0, 60.0, 61.0, 59.0, 58.0, 82.0, 58.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 73.0, 90.0, 70.0, 70.0, 74.0, 126.0, 69.0, 69.0, 95.0], "value": 82.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 128.0, 82.0, 112.0, 131.0, 91.0, 89.0, 86.0, 89.0, 87.0], "value": 101.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 95.0, 95.0, 94.0, 95.0, 96.0, 95.0, 95.0, 98.0], "value": 95.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 138.0, 97.0, 86.0, 84.0, 86.0, 85.0, 86.0, 85.0, 87.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 55.0, 56.0, 56.0, 56.0, 55.0, 56.0, 55.0, 56.0, 55.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 65.0, 75.0, 74.0, 67.0, 70.0, 69.0, 70.0, 69.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 84.0, 86.0, 84.0, 89.0, 88.0, 89.0, 97.0, 108.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 164.0, 197.0, 158.0, 158.0, 143.0, 145.0, 143.0, 179.0, 132.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 169.0, 176.0, 172.0, 173.0, 169.0, 172.0, 170.0, 174.0, 168.0], "value": 171.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 69.0, 69.0, 68.0, 67.0, 70.0, 68.0, 69.0, 68.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1484.9, "alertThreshold": 2.0}]} 17:40:49 INFO - Return code: 0 17:40:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:40:49 INFO - # TBPL SUCCESS # 17:40:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:40:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:40:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:40:49 INFO - 2016-10-12 17:40:49.091 screenresolution[1658:9723] starting screenresolution argv=screenresolution get 17:40:49 INFO - 2016-10-12 17:40:49.093 screenresolution[1658:9723] Display 0: 1600x1200x32@60 17:40:49 INFO - 2016-10-12 17:40:49.100 screenresolution[1659:9726] starting screenresolution argv=screenresolution list 17:40:49 INFO - Available Modes on Display 0 17:40:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:40:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:40:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:40:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:40:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:40:49 INFO - Intel Iris: 17:40:49 INFO - Chipset Model: Intel Iris 17:40:49 INFO - Type: GPU 17:40:49 INFO - Bus: Built-In 17:40:49 INFO - VRAM (Dynamic, Max): 1536 MB 17:40:49 INFO - Vendor: Intel (0x8086) 17:40:49 INFO - Device ID: 0x0a2e 17:40:49 INFO - Revision ID: 0x0009 17:40:49 INFO - Displays: 17:40:49 INFO - Display: 17:40:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:40:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:40:49 INFO - Display Serial Number: 1600x1200 60 17:40:49 INFO - Main Display: Yes 17:40:49 INFO - Mirror: Off 17:40:49 INFO - Online: Yes 17:40:49 INFO - Rotation: Supported 17:40:49 INFO - Return code: 0 17:40:49 INFO - Running post-action listener: _resource_record_post_action 17:40:49 INFO - [mozharness: 2016-10-13 00:40:49.337517Z] Finished run-tests step (success) 17:40:49 INFO - Running post-run listener: _resource_record_post_run 17:40:49 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 39333376; Write bytes: 706599424; Read time: 364; Write time: 1905 17:40:49 INFO - TinderboxPrint: CPU usage
53.4% 17:40:49 INFO - TinderboxPrint: I/O read bytes / time
39,333,376 / 364 17:40:49 INFO - TinderboxPrint: I/O write bytes / time
706,599,424 / 1,905 17:40:49 INFO - TinderboxPrint: CPU idle
2,264.0 (46.6%) 17:40:49 INFO - TinderboxPrint: CPU system
972.1 (20.0%) 17:40:49 INFO - TinderboxPrint: CPU user
1,625.5 (33.4%) 17:40:49 INFO - TinderboxPrint: Swap in / out
450,289,664 / 0 17:40:49 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 408171008; Write bytes: 410273792; Read time: 31073; Write time: 795 17:40:49 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 35507712; Write bytes: 294818304; Read time: 331; Write time: 1104 17:40:49 INFO - Running post-run listener: _upload_blobber_files 17:40:49 INFO - Blob upload gear active. 17:40:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:40:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:40:49 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:40:49 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:40:50 INFO - (blobuploader) - INFO - Open directory for files ... 17:40:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:40:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:40:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:51 INFO - (blobuploader) - INFO - Done attempting. 17:40:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:40:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:40:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:52 INFO - (blobuploader) - INFO - Done attempting. 17:40:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:40:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:40:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:55 INFO - (blobuploader) - INFO - Done attempting. 17:40:55 INFO - (blobuploader) - INFO - Iteration through files over. 17:40:55 INFO - Return code: 0 17:40:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:40:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:40:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92079ec1ccb41b01386f8e1c2f3232c744965001294fbf2e6dce54c49ee3fa79b3e96e15521f68bbe05d65204b4cc46a35b296bbc6966144ab6a45164b541264", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8f4ea11ae20bd9525a73ec6ebcc72b363eb470195b0c09fe70167ccf9dbdfc57ea82cdc0474d417e3c2dae3171577a37b0a483f2c8409735a89cee9dbbf0431", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa8273adcfc40394a13952f4c7837701a3209c2157568ab830bbea11e3bf2b406182110165fb8e5d89f4e411f4d133f7af7d335dbe577d383403afe36648632a"} 17:40:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:40:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:40:55 INFO - Contents: 17:40:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92079ec1ccb41b01386f8e1c2f3232c744965001294fbf2e6dce54c49ee3fa79b3e96e15521f68bbe05d65204b4cc46a35b296bbc6966144ab6a45164b541264", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8f4ea11ae20bd9525a73ec6ebcc72b363eb470195b0c09fe70167ccf9dbdfc57ea82cdc0474d417e3c2dae3171577a37b0a483f2c8409735a89cee9dbbf0431", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa8273adcfc40394a13952f4c7837701a3209c2157568ab830bbea11e3bf2b406182110165fb8e5d89f4e411f4d133f7af7d335dbe577d383403afe36648632a"} 17:40:55 INFO - Running post-run listener: copy_logs_to_upload_dir 17:40:55 INFO - Copying logs to upload dir... 17:40:55 INFO - mkdir: /builds/slave/test/build/upload/logs 17:40:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.982724 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-12 17:40:55.169954) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 17:40:55.173636) ========= 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.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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/Firefox/sha512/92079ec1ccb41b01386f8e1c2f3232c744965001294fbf2e6dce54c49ee3fa79b3e96e15521f68bbe05d65204b4cc46a35b296bbc6966144ab6a45164b541264", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8f4ea11ae20bd9525a73ec6ebcc72b363eb470195b0c09fe70167ccf9dbdfc57ea82cdc0474d417e3c2dae3171577a37b0a483f2c8409735a89cee9dbbf0431", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa8273adcfc40394a13952f4c7837701a3209c2157568ab830bbea11e3bf2b406182110165fb8e5d89f4e411f4d133f7af7d335dbe577d383403afe36648632a"} build_url:https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011069 build_url: 'https://queue.taskcluster.net/v1/task/Lu9AstGFRfyHPfT1TO5QbQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92079ec1ccb41b01386f8e1c2f3232c744965001294fbf2e6dce54c49ee3fa79b3e96e15521f68bbe05d65204b4cc46a35b296bbc6966144ab6a45164b541264", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8f4ea11ae20bd9525a73ec6ebcc72b363eb470195b0c09fe70167ccf9dbdfc57ea82cdc0474d417e3c2dae3171577a37b0a483f2c8409735a89cee9dbbf0431", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa8273adcfc40394a13952f4c7837701a3209c2157568ab830bbea11e3bf2b406182110165fb8e5d89f4e411f4d133f7af7d335dbe577d383403afe36648632a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 17:40:55.206749) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:40:55.207079) ========= 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.Fu4LCLB3fB/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.JX0FBRHbDp/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.004727 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:40:55.255054) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 17:40:55.255383) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 17:40:55.255806) ========= ========= Total master_lag: 0.71 =========