builder: cedar_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0340 starttime: 1478695960.57 results: success (0) buildid: 20161109022623 builduid: 895ccb49025445e2a2bd141e71060333 revision: 533b6efe3528fcb022c857e84bc432b77b5507ef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:40.573831) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:40.574271) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:40.574546) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:40.597984) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:40.598338) ========= 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.ZOEZfFQIsW/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.LdHtJYXIws/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-09 04:52:40-- 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% 36.6M=0s 2016-11-09 04:52:40 (36.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.097182 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:40.717626) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:40.717988) ========= 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.ZOEZfFQIsW/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.LdHtJYXIws/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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.020731 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:40.766750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:40.767112) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 533b6efe3528fcb022c857e84bc432b77b5507ef --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev 533b6efe3528fcb022c857e84bc432b77b5507ef --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZOEZfFQIsW/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.LdHtJYXIws/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-09 04:52:40,849 truncating revision to first 12 chars 2016-11-09 04:52:40,849 Setting DEBUG logging. 2016-11-09 04:52:40,849 attempt 1/10 2016-11-09 04:52:40,849 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/533b6efe3528?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-09 04:52:41,187 unpacking tar archive at: cedar-533b6efe3528/testing/mozharness/ program finished with exit code 0 elapsedTime=0.589048 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:41.378398) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:41.378698) ========= script_repo_revision: 533b6efe3528fcb022c857e84bc432b77b5507ef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:41.379211) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:41.379513) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:41.404138) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-09 04:52:41.404505) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZOEZfFQIsW/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.LdHtJYXIws/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 04:52:41 INFO - MultiFileLogger online at 20161109 04:52:41 in /builds/slave/test 04:52:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 04:52:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:52:41 INFO - {'append_to_log': False, 04:52:41 INFO - 'base_work_dir': '/builds/slave/test', 04:52:41 INFO - 'blob_upload_branch': 'Cedar', 04:52:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:52:41 INFO - 'branch': 'Cedar', 04:52:41 INFO - 'buildbot_json_path': 'buildprops.json', 04:52:41 INFO - 'config_files': ('talos/mac_config.py',), 04:52:41 INFO - 'default_actions': ('clobber', 04:52:41 INFO - 'read-buildbot-config', 04:52:41 INFO - 'download-and-extract', 04:52:41 INFO - 'populate-webroot', 04:52:41 INFO - 'create-virtualenv', 04:52:41 INFO - 'install', 04:52:41 INFO - 'run-tests'), 04:52:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:52:41 INFO - 'download_minidump_stackwalk': True, 04:52:41 INFO - 'download_symbols': 'ondemand', 04:52:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:52:41 INFO - 'tooltool.py': '/tools/tooltool.py', 04:52:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:52:41 INFO - '/tools/misc-python/virtualenv.py')}, 04:52:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:52:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:52:41 INFO - 'installer_path': 'installer.exe', 04:52:41 INFO - 'log_level': 'info', 04:52:41 INFO - 'log_name': 'talos', 04:52:41 INFO - 'log_to_console': True, 04:52:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:52:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:52:41 INFO - 'opt_config_files': (), 04:52:41 INFO - 'pip_index': False, 04:52:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:52:41 INFO - 'cmd': ('bash', 04:52:41 INFO - '-c', 04:52:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:52:41 INFO - 'enabled': True, 04:52:41 INFO - 'halt_on_failure': False, 04:52:41 INFO - 'name': 'check_screen_resolution'},), 04:52:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:52:41 INFO - 'cmd': ('bash', 04:52:41 INFO - '-c', 04:52:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:52:41 INFO - 'enabled': True, 04:52:41 INFO - 'halt_on_failure': False, 04:52:41 INFO - 'name': 'check_screen_resolution'},), 04:52:41 INFO - 'run_cmd_checks_enabled': True, 04:52:41 INFO - 'sps_profile': False, 04:52:41 INFO - 'sps_profile_interval': 0, 04:52:41 INFO - 'suite': 'dromaeojs', 04:52:41 INFO - 'system_bits': '32', 04:52:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:52:41 INFO - 'title': 't-yosemite-r7-0340', 04:52:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:52:41 INFO - 'use_talos_json': True, 04:52:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:52:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:52:41 INFO - 'work_dir': 'build'} 04:52:41 INFO - [mozharness: 2016-11-09 12:52:41.603917Z] Running clobber step. 04:52:41 INFO - Running pre-action listener: _resource_record_pre_action 04:52:41 INFO - Running main action method: clobber 04:52:41 INFO - rmtree: /builds/slave/test/build 04:52:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:52:42 INFO - Running post-action listener: _resource_record_post_action 04:52:42 INFO - [mozharness: 2016-11-09 12:52:42.245296Z] Finished clobber step (success) 04:52:42 INFO - [mozharness: 2016-11-09 12:52:42.245418Z] Running read-buildbot-config step. 04:52:42 INFO - Running pre-action listener: _resource_record_pre_action 04:52:42 INFO - Running main action method: read_buildbot_config 04:52:42 INFO - Using buildbot properties: 04:52:42 INFO - { 04:52:42 INFO - "project": "", 04:52:42 INFO - "product": "firefox", 04:52:42 INFO - "script_repo_revision": "default", 04:52:42 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs", 04:52:42 INFO - "repository": "", 04:52:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs", 04:52:42 INFO - "buildid": "20161109022623", 04:52:42 INFO - "pgo_build": "False", 04:52:42 INFO - "basedir": "/builds/slave/test", 04:52:42 INFO - "buildnumber": 1, 04:52:42 INFO - "slavename": "t-yosemite-r7-0340", 04:52:42 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:52:42 INFO - "platform": "yosemite_r7", 04:52:42 INFO - "scheduler": "tests-cedar-macosx64-talos", 04:52:42 INFO - "branch": "cedar", 04:52:42 INFO - "repo_path": "projects/cedar", 04:52:42 INFO - "revision": "533b6efe3528fcb022c857e84bc432b77b5507ef", 04:52:42 INFO - "stage_platform": "macosx64", 04:52:42 INFO - "builduid": "895ccb49025445e2a2bd141e71060333", 04:52:42 INFO - "slavebuilddir": "test" 04:52:42 INFO - } 04:52:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 04:52:42 INFO - Running post-action listener: _resource_record_post_action 04:52:42 INFO - [mozharness: 2016-11-09 12:52:42.249011Z] Finished read-buildbot-config step (success) 04:52:42 INFO - [mozharness: 2016-11-09 12:52:42.249116Z] Running download-and-extract step. 04:52:42 INFO - Running pre-action listener: _resource_record_pre_action 04:52:42 INFO - Running main action method: download_and_extract 04:52:42 INFO - mkdir: /builds/slave/test/build/tests 04:52:42 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')]} 04:52:42 INFO - trying https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 04:52:42 INFO - Downloading https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/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 04:52:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/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 04:52:43 INFO - Downloaded 1199 bytes. 04:52:43 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:52:43 INFO - Using the following test package requirements: 04:52:43 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:52:43 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:43 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 04:52:43 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:43 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 04:52:43 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:43 INFO - u'jsshell-mac.zip'], 04:52:43 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:43 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 04:52:43 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:52:43 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:43 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 04:52:43 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:43 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 04:52:43 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:43 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 04:52:43 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:43 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 04:52:43 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:52:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:52:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:52:43 INFO - Fetch https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:52:43 INFO - Http code: 200 04:52:43 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 04:52:43 INFO - x-amz-cf-id: qi-Z2cgInJYJU2O1N8IYjhnjg1m2KgB3O5WJkrw5FETKxqiywJALHQ== 04:52:43 INFO - x-amz-version-id: w_8lZipQRrfjEoqViEV2_o7Vb6P8NV9L 04:52:44 INFO - Expected file size: 16318213 04:52:44 INFO - Obtained file size: 16318213 04:52:50 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 04:52:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:52:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:52:50 INFO - Fetch https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:52:51 INFO - Http code: 200 04:52:51 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 04:52:51 INFO - x-amz-cf-id: RVdEteRkioqNIW8XumNcYN_yMWCm_gC04v64lDolFvaNd86G_0X_hg== 04:52:51 INFO - x-amz-version-id: w_8lZipQRrfjEoqViEV2_o7Vb6P8NV9L 04:52:51 INFO - Expected file size: 16318213 04:52:51 INFO - Obtained file size: 16318213 04:52:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 04:52:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:52:57 INFO - Fetch https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 04:52:58 INFO - Http code: 200 04:52:58 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 04:52:58 INFO - x-amz-cf-id: ceFI0N6fupFbBY5iAPkCCl7ohb6dRgD3XtxizNn6VDK9ZJdbEGRvWw== 04:52:58 INFO - x-amz-version-id: 5ST5RRKVa1NPLDN9XuTJw5gs5sx.FCOG 04:52:58 INFO - Expected file size: 14409038 04:52:58 INFO - Obtained file size: 14409038 04:52:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:59 INFO - trying https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:52:59 INFO - Downloading https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 04:52:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/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 04:53:01 INFO - Downloaded 111558996 bytes. 04:53:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:53:01 INFO - mkdir: /builds/slave/test/properties 04:53:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:53:01 INFO - Writing to file /builds/slave/test/properties/build_url 04:53:01 INFO - Contents: 04:53:01 INFO - build_url:https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:53:02 INFO - Running post-action listener: _resource_record_post_action 04:53:02 INFO - Running post-action listener: set_extra_try_arguments 04:53:02 INFO - [mozharness: 2016-11-09 12:53:02.506582Z] Finished download-and-extract step (success) 04:53:02 INFO - [mozharness: 2016-11-09 12:53:02.506715Z] Running populate-webroot step. 04:53:02 INFO - Running pre-action listener: _resource_record_pre_action 04:53:02 INFO - Running main action method: populate_webroot 04:53:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:53:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:53:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:53:02 INFO - '--tppagecycles', 04:53:02 INFO - '7'], 04:53:02 INFO - 'tests': ['tsvgm']}}, 04:53:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:53:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:53:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:53:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:53:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:53:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:53:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:02 INFO - 'talos_options': ['--disable-e10s'], 04:53:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:53:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:53:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:02 INFO - 'talos_options': ['--disable-e10s'], 04:53:02 INFO - 'tests': ['damp', 'tps']}, 04:53:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:02 INFO - 'tests': ['damp', 'tps']}, 04:53:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:53:02 INFO - 'tests': ['dromaeo_dom']}, 04:53:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:53:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:53:02 INFO - 'tests': ['basic_compositor_video']}, 04:53:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:53:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:53:02 INFO - 'tests': ['a11yr', 04:53:02 INFO - 'ts_paint', 04:53:02 INFO - 'tpaint', 04:53:02 INFO - 'sessionrestore', 04:53:02 INFO - 'sessionrestore_no_auto_restore', 04:53:02 INFO - 'tabpaint']}, 04:53:02 INFO - 'other-e10s': {'tests': ['a11yr', 04:53:02 INFO - 'ts_paint', 04:53:02 INFO - 'tpaint', 04:53:02 INFO - 'sessionrestore', 04:53:02 INFO - 'sessionrestore_no_auto_restore', 04:53:02 INFO - 'tabpaint']}, 04:53:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:53:02 INFO - 'ts_paint', 04:53:02 INFO - 'tpaint', 04:53:02 INFO - 'sessionrestore', 04:53:02 INFO - 'sessionrestore_no_auto_restore', 04:53:02 INFO - 'tabpaint']}, 04:53:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:53:02 INFO - 'ts_paint', 04:53:02 INFO - 'tpaint', 04:53:02 INFO - 'sessionrestore', 04:53:02 INFO - 'sessionrestore_no_auto_restore', 04:53:02 INFO - 'tabpaint']}, 04:53:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:53:02 INFO - 'tests': ['a11yr', 04:53:02 INFO - 'ts_paint', 04:53:02 INFO - 'tpaint', 04:53:02 INFO - 'sessionrestore', 04:53:02 INFO - 'sessionrestore_no_auto_restore', 04:53:02 INFO - 'tabpaint']}, 04:53:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:53:02 INFO - 'tests': ['a11yr', 04:53:02 INFO - 'ts_paint', 04:53:02 INFO - 'tpaint', 04:53:02 INFO - 'sessionrestore', 04:53:02 INFO - 'sessionrestore_no_auto_restore', 04:53:02 INFO - 'tabpaint']}, 04:53:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:53:02 INFO - 'tests': ['tsvgx', 04:53:02 INFO - 'tsvgr_opacity', 04:53:02 INFO - 'tart', 04:53:02 INFO - 'tscrollx', 04:53:02 INFO - 'cart']}, 04:53:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:53:02 INFO - 'tsvgr_opacity', 04:53:02 INFO - 'tart', 04:53:02 INFO - 'tscrollx', 04:53:02 INFO - 'cart']}, 04:53:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:02 INFO - 'talos_options': ['--disable-e10s'], 04:53:02 INFO - 'tests': ['tp5o']}, 04:53:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:02 INFO - 'tests': ['tp5o']}, 04:53:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:02 INFO - 'talos_options': ['--disable-e10s', 04:53:02 INFO - '--xperf_path', 04:53:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:53:02 INFO - 'tests': ['tp5n']}, 04:53:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:02 INFO - 'talos_options': ['--xperf_path', 04:53:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:53:02 INFO - 'tests': ['tp5n']}}, 04:53:02 INFO - 'talos.zip': {'path': '', 04:53:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:53:02 INFO - Running post-action listener: _resource_record_post_action 04:53:02 INFO - [mozharness: 2016-11-09 12:53:02.525482Z] Finished populate-webroot step (success) 04:53:02 INFO - [mozharness: 2016-11-09 12:53:02.525595Z] Running create-virtualenv step. 04:53:02 INFO - Running pre-action listener: _resource_record_pre_action 04:53:02 INFO - Running main action method: create_virtualenv 04:53:02 INFO - Creating virtualenv /builds/slave/test/build/venv 04:53:02 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 04:53:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:53:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:53:02 INFO - Using real prefix '/tools/python27' 04:53:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:53:03 INFO - Installing distribute.............................................................................................................................................................................................done. 04:53:06 INFO - Installing pip.................done. 04:53:06 INFO - Return code: 0 04:53:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:53:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:53:06 INFO - Reading from file tmpfile_stdout 04:53:06 INFO - Output received: 04:53:06 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) 04:53:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:06 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 0x10f24e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa35a5b82d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f062d30>, '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 0x10ee88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f05ab20>, '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.ZOEZfFQIsW/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.LdHtJYXIws/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 04:53:06 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 04:53:06 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 04:53:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOEZfFQIsW/Render', 04:53:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:06 INFO - 'HOME': '/Users/cltbld', 04:53:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:06 INFO - 'LOGNAME': 'cltbld', 04:53:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:06 INFO - 'MOZ_NO_REMOTE': '1', 04:53:06 INFO - 'NO_EM_RESTART': '1', 04:53:06 INFO - 'PAGER': '/bin/cat', 04:53:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:06 INFO - 'PWD': '/builds/slave/test', 04:53:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:06 INFO - 'SHELL': '/bin/bash', 04:53:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdHtJYXIws/Listeners', 04:53:06 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 04:53:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:06 INFO - 'USER': 'cltbld', 04:53:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:06 INFO - 'XPC_FLAGS': '0x0', 04:53:06 INFO - 'XPC_SERVICE_NAME': '0', 04:53:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:06 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 04:53:06 INFO - Cleaning up... 04:53:06 INFO - Return code: 0 04:53:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:06 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 0x10f24e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa35a5b82d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f062d30>, '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 0x10ee88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f05ab20>, '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.ZOEZfFQIsW/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.LdHtJYXIws/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 04:53:06 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 04:53:06 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 04:53:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOEZfFQIsW/Render', 04:53:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:06 INFO - 'HOME': '/Users/cltbld', 04:53:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:06 INFO - 'LOGNAME': 'cltbld', 04:53:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:06 INFO - 'MOZ_NO_REMOTE': '1', 04:53:06 INFO - 'NO_EM_RESTART': '1', 04:53:06 INFO - 'PAGER': '/bin/cat', 04:53:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:06 INFO - 'PWD': '/builds/slave/test', 04:53:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:06 INFO - 'SHELL': '/bin/bash', 04:53:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdHtJYXIws/Listeners', 04:53:06 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 04:53:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:06 INFO - 'USER': 'cltbld', 04:53:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:06 INFO - 'XPC_FLAGS': '0x0', 04:53:06 INFO - 'XPC_SERVICE_NAME': '0', 04:53:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:07 INFO - Downloading/unpacking psutil>=3.1.1 04:53:07 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 04:53:07 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 04:53:07 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 04:53:07 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 04:53:07 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 04:53:07 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 04:53:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:53:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:53:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:53:10 INFO - Installing collected packages: psutil 04:53:10 INFO - Running setup.py install for psutil 04:53:10 INFO - building 'psutil._psutil_osx' extension 04:53:10 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 04:53:10 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 04:53:10 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 04:53:10 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 04:53:10 INFO - building 'psutil._psutil_posix' extension 04:53:10 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 04:53:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:53:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:53:10 INFO - ^ 04:53:11 INFO - 1 warning generated. 04:53:11 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 04:53:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:53:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:53:11 INFO - Successfully installed psutil 04:53:11 INFO - Cleaning up... 04:53:11 INFO - Return code: 0 04:53:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:53:11 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')]} 04:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:11 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')]} 04:53:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:11 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 0x10f24e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa35a5b82d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f062d30>, '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 0x10ee88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f05ab20>, '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.ZOEZfFQIsW/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.LdHtJYXIws/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 04:53:11 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 04:53:11 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 04:53:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOEZfFQIsW/Render', 04:53:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:11 INFO - 'HOME': '/Users/cltbld', 04:53:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:11 INFO - 'LOGNAME': 'cltbld', 04:53:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:11 INFO - 'MOZ_NO_REMOTE': '1', 04:53:11 INFO - 'NO_EM_RESTART': '1', 04:53:11 INFO - 'PAGER': '/bin/cat', 04:53:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:11 INFO - 'PWD': '/builds/slave/test', 04:53:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:11 INFO - 'SHELL': '/bin/bash', 04:53:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdHtJYXIws/Listeners', 04:53:11 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 04:53:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:11 INFO - 'USER': 'cltbld', 04:53:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:11 INFO - 'XPC_FLAGS': '0x0', 04:53:11 INFO - 'XPC_SERVICE_NAME': '0', 04:53:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:53:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:53:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:53:14 INFO - Installing collected packages: mozsystemmonitor 04:53:14 INFO - Running setup.py install for mozsystemmonitor 04:53:14 INFO - Successfully installed mozsystemmonitor 04:53:14 INFO - Cleaning up... 04:53:14 INFO - Return code: 0 04:53:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:14 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 0x10f24e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa35a5b82d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f062d30>, '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 0x10ee88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f05ab20>, '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.ZOEZfFQIsW/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.LdHtJYXIws/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 04:53:14 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 04:53:14 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 04:53:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOEZfFQIsW/Render', 04:53:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:14 INFO - 'HOME': '/Users/cltbld', 04:53:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:14 INFO - 'LOGNAME': 'cltbld', 04:53:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:14 INFO - 'MOZ_NO_REMOTE': '1', 04:53:14 INFO - 'NO_EM_RESTART': '1', 04:53:14 INFO - 'PAGER': '/bin/cat', 04:53:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:14 INFO - 'PWD': '/builds/slave/test', 04:53:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:14 INFO - 'SHELL': '/bin/bash', 04:53:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdHtJYXIws/Listeners', 04:53:14 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 04:53:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:14 INFO - 'USER': 'cltbld', 04:53:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:14 INFO - 'XPC_FLAGS': '0x0', 04:53:14 INFO - 'XPC_SERVICE_NAME': '0', 04:53:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:14 INFO - Downloading/unpacking jsonschema==2.5.1 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 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 04:53:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:53:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:53:17 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 04:53:17 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 04:53:17 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 04:53:17 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 04:53:17 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 04:53:17 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 04:53:17 INFO - Downloading functools32-3.2.3-2.tar.gz 04:53:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:53:17 INFO - warning: no files found matching '*.txt' 04:53:17 INFO - no previously-included directories found matching 'build' 04:53:17 INFO - no previously-included directories found matching 'dist' 04:53:17 INFO - no previously-included directories found matching '.git*' 04:53:17 INFO - Installing collected packages: jsonschema, functools32 04:53:17 INFO - Running setup.py install for functools32 04:53:17 INFO - warning: no files found matching '*.txt' 04:53:17 INFO - no previously-included directories found matching 'build' 04:53:17 INFO - no previously-included directories found matching 'dist' 04:53:17 INFO - no previously-included directories found matching '.git*' 04:53:17 INFO - Successfully installed jsonschema functools32 04:53:17 INFO - Cleaning up... 04:53:17 INFO - Return code: 0 04:53:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:53:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f24e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa35a5b82d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f062d30>, '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 0x10ee88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f05ab20>, '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.ZOEZfFQIsW/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.LdHtJYXIws/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 04:53:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:53:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:53:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOEZfFQIsW/Render', 04:53:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:17 INFO - 'HOME': '/Users/cltbld', 04:53:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:17 INFO - 'LOGNAME': 'cltbld', 04:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:17 INFO - 'MOZ_NO_REMOTE': '1', 04:53:17 INFO - 'NO_EM_RESTART': '1', 04:53:17 INFO - 'PAGER': '/bin/cat', 04:53:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:17 INFO - 'PWD': '/builds/slave/test', 04:53:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:17 INFO - 'SHELL': '/bin/bash', 04:53:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdHtJYXIws/Listeners', 04:53:17 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 04:53:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:17 INFO - 'USER': 'cltbld', 04:53:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:17 INFO - 'XPC_FLAGS': '0x0', 04:53:17 INFO - 'XPC_SERVICE_NAME': '0', 04:53:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:53:18 INFO - Cleaning up... 04:53:18 INFO - Return code: 0 04:53:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:18 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 0x10f24e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa35a5b82d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f062d30>, '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 0x10ee88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f05ab20>, '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.ZOEZfFQIsW/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.LdHtJYXIws/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 04:53:18 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 04:53:18 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 04:53:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOEZfFQIsW/Render', 04:53:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:18 INFO - 'HOME': '/Users/cltbld', 04:53:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:18 INFO - 'LOGNAME': 'cltbld', 04:53:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:18 INFO - 'MOZ_NO_REMOTE': '1', 04:53:18 INFO - 'NO_EM_RESTART': '1', 04:53:18 INFO - 'PAGER': '/bin/cat', 04:53:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:18 INFO - 'PWD': '/builds/slave/test', 04:53:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:18 INFO - 'SHELL': '/bin/bash', 04:53:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdHtJYXIws/Listeners', 04:53:18 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 04:53:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:18 INFO - 'USER': 'cltbld', 04:53:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:18 INFO - 'XPC_FLAGS': '0x0', 04:53:18 INFO - 'XPC_SERVICE_NAME': '0', 04:53:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:18 INFO - Downloading/unpacking blobuploader==1.2.4 04:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:20 INFO - Downloading blobuploader-1.2.4.tar.gz 04:53:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:53:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:53:20 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 04:53:20 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 04:53:20 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 04:53:20 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 04:53:20 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 04:53:20 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 04:53:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:53:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:53:21 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 04:53:21 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 04:53:21 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 04:53:21 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 04:53:21 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 04:53:21 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 04:53:22 INFO - Downloading docopt-0.6.1.tar.gz 04:53:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:53:22 INFO - Installing collected packages: blobuploader, requests, docopt 04:53:22 INFO - Running setup.py install for blobuploader 04:53:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:53:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:53:22 INFO - Running setup.py install for requests 04:53:22 INFO - Running setup.py install for docopt 04:53:22 INFO - Successfully installed blobuploader requests docopt 04:53:22 INFO - Cleaning up... 04:53:23 INFO - Return code: 0 04:53:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:23 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 0x10f24e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa35a5b82d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f062d30>, '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 0x10ee88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f05ab20>, '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.ZOEZfFQIsW/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.LdHtJYXIws/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 04:53:23 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 04:53:23 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 04:53:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOEZfFQIsW/Render', 04:53:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:23 INFO - 'HOME': '/Users/cltbld', 04:53:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:23 INFO - 'LOGNAME': 'cltbld', 04:53:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:23 INFO - 'MOZ_NO_REMOTE': '1', 04:53:23 INFO - 'NO_EM_RESTART': '1', 04:53:23 INFO - 'PAGER': '/bin/cat', 04:53:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:23 INFO - 'PWD': '/builds/slave/test', 04:53:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:23 INFO - 'SHELL': '/bin/bash', 04:53:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdHtJYXIws/Listeners', 04:53:23 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 04:53:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:23 INFO - 'USER': 'cltbld', 04:53:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:23 INFO - 'XPC_FLAGS': '0x0', 04:53:23 INFO - 'XPC_SERVICE_NAME': '0', 04:53:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:53:23 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-BbZUal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:53:23 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-P5ZlTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:53:23 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-JQ2h47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:53:23 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-YDmp3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:53:23 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-rLQ9S8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:53:23 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-xt10Vq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:53:23 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-1mr1ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:53:24 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-xqn0YG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:53:24 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-dyiPCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:53:24 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-1jV33S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:53:24 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-qs_W0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:53:24 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-zow5uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:53:24 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-i5Hw34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:53:24 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-Wc0OM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:24 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-sLN0YC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:53:24 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-8oJm1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:53:24 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-95rA0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:53:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:53:25 INFO - Running setup.py install for manifestparser 04:53:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:53:25 INFO - Running setup.py install for mozcrash 04:53:25 INFO - Running setup.py install for mozdebug 04:53:25 INFO - Running setup.py install for mozdevice 04:53:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:53:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:53:25 INFO - Running setup.py install for mozfile 04:53:25 INFO - Running setup.py install for mozhttpd 04:53:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:53:25 INFO - Running setup.py install for mozinfo 04:53:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:53:26 INFO - Running setup.py install for mozInstall 04:53:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:53:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:53:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:53:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:53:26 INFO - Running setup.py install for mozleak 04:53:26 INFO - Running setup.py install for mozlog 04:53:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:53:26 INFO - Running setup.py install for moznetwork 04:53:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:53:26 INFO - Running setup.py install for mozprocess 04:53:26 INFO - Running setup.py install for mozprofile 04:53:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:53:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:53:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:53:27 INFO - Running setup.py install for mozrunner 04:53:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:53:27 INFO - Running setup.py install for mozscreenshot 04:53:27 INFO - Running setup.py install for moztest 04:53:27 INFO - Running setup.py install for mozversion 04:53:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:53:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:53:27 INFO - Cleaning up... 04:53:27 INFO - Return code: 0 04:53:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53:27 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')]} 04:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:27 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')]} 04:53:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:27 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 0x10f24e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa35a5b82d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f062d30>, '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 0x10ee88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f05ab20>, '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.ZOEZfFQIsW/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.LdHtJYXIws/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 04:53:27 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 04:53:27 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 04:53:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOEZfFQIsW/Render', 04:53:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:27 INFO - 'HOME': '/Users/cltbld', 04:53:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:27 INFO - 'LOGNAME': 'cltbld', 04:53:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:27 INFO - 'MOZ_NO_REMOTE': '1', 04:53:27 INFO - 'NO_EM_RESTART': '1', 04:53:27 INFO - 'PAGER': '/bin/cat', 04:53:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:27 INFO - 'PWD': '/builds/slave/test', 04:53:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:27 INFO - 'SHELL': '/bin/bash', 04:53:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdHtJYXIws/Listeners', 04:53:27 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 04:53:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:27 INFO - 'USER': 'cltbld', 04:53:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:27 INFO - 'XPC_FLAGS': '0x0', 04:53:27 INFO - 'XPC_SERVICE_NAME': '0', 04:53:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:53:27 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-_qeM4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:53:28 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)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:53:28 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-9sbYfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:53:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:53:28 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-spASqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:53:28 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)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:53:28 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-tlCZ7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:53:28 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)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:53:28 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-ASc4g3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:53:28 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)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:53:28 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-thecdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:53:28 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)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:53:28 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-v7xtlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:53:28 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)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:53:28 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-8CMD8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:53:28 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)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:53:28 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-QCogxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:53:28 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)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:53:28 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-PghfPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:53:29 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)) 04:53:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:53:29 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-AD2Esd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:53:29 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)) 04:53:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:53:29 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-rozPEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:53:29 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)) 04:53:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:53:29 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-rvolaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:53:29 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)) 04:53:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:53:29 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-4B76kp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:53:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:29 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-3ciSVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:29 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)) 04:53:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:53:29 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-aBebNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:53:29 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)) 04:53:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:53:29 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-EURDEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:53:29 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)) 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:53:29 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)) 04:53:29 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)) 04:53:29 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)) 04:53:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:53:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:32 INFO - Downloading blessings-1.6.tar.gz 04:53:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:53:32 INFO - Installing collected packages: blessings 04:53:32 INFO - Running setup.py install for blessings 04:53:32 INFO - Successfully installed blessings 04:53:32 INFO - Cleaning up... 04:53:32 INFO - Return code: 0 04:53:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:53:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:53:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:53:32 INFO - Reading from file tmpfile_stdout 04:53:32 INFO - Current package versions: 04:53:32 INFO - blessings == 1.6 04:53:32 INFO - blobuploader == 1.2.4 04:53:32 INFO - docopt == 0.6.1 04:53:32 INFO - functools32 == 3.2.3-2 04:53:32 INFO - jsonschema == 2.5.1 04:53:32 INFO - manifestparser == 1.1 04:53:32 INFO - mozInstall == 1.12 04:53:32 INFO - mozcrash == 1.0 04:53:32 INFO - mozdebug == 0.1 04:53:32 INFO - mozdevice == 0.48 04:53:32 INFO - mozfile == 1.2 04:53:32 INFO - mozhttpd == 0.7 04:53:32 INFO - mozinfo == 0.9 04:53:32 INFO - mozleak == 0.1 04:53:32 INFO - mozlog == 3.4 04:53:32 INFO - moznetwork == 0.27 04:53:32 INFO - mozprocess == 0.23 04:53:32 INFO - mozprofile == 0.28 04:53:32 INFO - mozrunner == 6.13 04:53:32 INFO - mozscreenshot == 0.1 04:53:32 INFO - mozsystemmonitor == 0.3 04:53:32 INFO - moztest == 0.7 04:53:32 INFO - mozversion == 1.4 04:53:32 INFO - psutil == 3.1.1 04:53:32 INFO - requests == 1.2.3 04:53:32 INFO - wsgiref == 0.1.2 04:53:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53: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')]} 04:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53: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')]} 04:53:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53: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 0x10f24e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa35a5b82d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f062d30>, '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 0x10ee88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f05ab20>, '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.ZOEZfFQIsW/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.LdHtJYXIws/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 04:53: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 04:53: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 04:53:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOEZfFQIsW/Render', 04:53:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:32 INFO - 'HOME': '/Users/cltbld', 04:53:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:32 INFO - 'LOGNAME': 'cltbld', 04:53:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:32 INFO - 'MOZ_NO_REMOTE': '1', 04:53:32 INFO - 'NO_EM_RESTART': '1', 04:53:32 INFO - 'PAGER': '/bin/cat', 04:53:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:32 INFO - 'PWD': '/builds/slave/test', 04:53:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:32 INFO - 'SHELL': '/bin/bash', 04:53:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdHtJYXIws/Listeners', 04:53:32 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 04:53:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:32 INFO - 'USER': 'cltbld', 04:53:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:32 INFO - 'XPC_FLAGS': '0x0', 04:53:32 INFO - 'XPC_SERVICE_NAME': '0', 04:53:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 INFO - Cleaning up... 04:53:33 INFO - Return code: 0 04:53:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:53:33 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')]} 04:53:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:33 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')]} 04:53:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:33 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 0x10f24e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa35a5b82d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f062d30>, '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 0x10ee88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f05ab20>, '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.ZOEZfFQIsW/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.LdHtJYXIws/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 04:53:33 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 04:53:33 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 04:53:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOEZfFQIsW/Render', 04:53:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:33 INFO - 'HOME': '/Users/cltbld', 04:53:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:33 INFO - 'LOGNAME': 'cltbld', 04:53:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:33 INFO - 'MOZ_NO_REMOTE': '1', 04:53:33 INFO - 'NO_EM_RESTART': '1', 04:53:33 INFO - 'PAGER': '/bin/cat', 04:53:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:33 INFO - 'PWD': '/builds/slave/test', 04:53:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:33 INFO - 'SHELL': '/bin/bash', 04:53:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdHtJYXIws/Listeners', 04:53:33 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 04:53:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:33 INFO - 'USER': 'cltbld', 04:53:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:33 INFO - 'XPC_FLAGS': '0x0', 04:53:33 INFO - 'XPC_SERVICE_NAME': '0', 04:53:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:53:33 INFO - Cleaning up... 04:53:33 INFO - Return code: 0 04:53:33 INFO - Running post-action listener: _resource_record_post_action 04:53:33 INFO - Running post-action listener: _start_resource_monitoring 04:53:33 INFO - Starting resource monitoring. 04:53:33 INFO - [mozharness: 2016-11-09 12:53:33.450422Z] Finished create-virtualenv step (success) 04:53:33 INFO - [mozharness: 2016-11-09 12:53:33.451501Z] Running install step. 04:53:33 INFO - Running pre-action listener: _resource_record_pre_action 04:53:33 INFO - Running main action method: install 04:53:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:53:33 INFO - Reading from file tmpfile_stdout 04:53:33 INFO - Detecting whether we're running mozinstall >=1.0... 04:53:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:53:33 INFO - Reading from file tmpfile_stdout 04:53:33 INFO - Output received: 04:53:33 INFO - Usage: mozinstall [options] installer 04:53:33 INFO - Options: 04:53:33 INFO - -h, --help show this help message and exit 04:53:33 INFO - -d DEST, --destination=DEST 04:53:33 INFO - Directory to install application into. [default: 04:53:33 INFO - "/builds/slave/test"] 04:53:33 INFO - --app=APP Application being installed. [default: firefox] 04:53:33 INFO - mkdir: /builds/slave/test/build/application 04:53: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'] 04:53: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 04:54:07 INFO - Reading from file tmpfile_stdout 04:54:07 INFO - Output received: 04:54:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:54:07 INFO - Running post-action listener: _resource_record_post_action 04:54:07 INFO - [mozharness: 2016-11-09 12:54:07.705087Z] Finished install step (success) 04:54:07 INFO - [mozharness: 2016-11-09 12:54:07.705227Z] Running run-tests step. 04:54:07 INFO - Running pre-action listener: _resource_record_pre_action 04:54:07 INFO - Running main action method: run_tests 04:54:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:54:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:54:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:54:07 INFO - 2016-11-09 04:54:07.720 screenresolution[6717:126320] starting screenresolution argv=screenresolution get 04:54:07 INFO - 2016-11-09 04:54:07.722 screenresolution[6717:126320] Display 0: 1600x1200x32@60 04:54:07 INFO - 2016-11-09 04:54:07.728 screenresolution[6718:126322] starting screenresolution argv=screenresolution list 04:54:07 INFO - Available Modes on Display 0 04:54:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:54:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:54:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:54:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:54:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:54:07 INFO - Intel Iris: 04:54:07 INFO - Chipset Model: Intel Iris 04:54:07 INFO - Type: GPU 04:54:07 INFO - Bus: Built-In 04:54:07 INFO - VRAM (Dynamic, Max): 1536 MB 04:54:07 INFO - Vendor: Intel (0x8086) 04:54:07 INFO - Device ID: 0x0a2e 04:54:07 INFO - Revision ID: 0x0009 04:54:07 INFO - Displays: 04:54:07 INFO - Display: 04:54:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:54:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:54:07 INFO - Display Serial Number: 1600x1200 60 04:54:07 INFO - Main Display: Yes 04:54:07 INFO - Mirror: Off 04:54:07 INFO - Online: Yes 04:54:07 INFO - Rotation: Supported 04:54:07 INFO - Return code: 0 04:54:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:54:07 INFO - Python 2.7.3 04:54:07 INFO - Return code: 0 04:54:07 INFO - grabbing minidump binary from tooltool 04:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:07 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 0x10f062d30>, '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 0x10ee88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f05ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:54:07 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 04:54:07 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 04:54:07 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 04:54:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:54:08 INFO - Return code: 0 04:54:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:54:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:54:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:54:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:54:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:54:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0340', '--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 04:54:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0340 --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 04:54:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOEZfFQIsW/Render', 04:54:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:08 INFO - 'HOME': '/Users/cltbld', 04:54:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:08 INFO - 'LOGNAME': 'cltbld', 04:54:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:54:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:54:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:08 INFO - 'MOZ_NO_REMOTE': '1', 04:54:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:54:08 INFO - 'NO_EM_RESTART': '1', 04:54:08 INFO - 'PAGER': '/bin/cat', 04:54:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:08 INFO - 'PWD': '/builds/slave/test', 04:54:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:54:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:08 INFO - 'SHELL': '/bin/bash', 04:54:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdHtJYXIws/Listeners', 04:54:08 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 04:54:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:08 INFO - 'USER': 'cltbld', 04:54:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:08 INFO - 'XPC_FLAGS': '0x0', 04:54:08 INFO - 'XPC_SERVICE_NAME': '0', 04:54:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0340', '--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 04:54:08 INFO - mozversion application_buildid: 20161109022623 04:54:08 INFO - mozversion application_changeset: 533b6efe3528fcb022c857e84bc432b77b5507ef 04:54:08 INFO - mozversion application_display_name: Nightly 04:54:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:54:08 INFO - mozversion application_name: Firefox 04:54:08 INFO - mozversion application_remotingname: firefox 04:54:08 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 04:54:08 INFO - mozversion application_vendor: Mozilla 04:54:08 INFO - mozversion application_version: 52.0a1 04:54:08 INFO - mozversion platform_buildid: 20161109022623 04:54:08 INFO - mozversion platform_changeset: 533b6efe3528fcb022c857e84bc432b77b5507ef 04:54:08 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 04:54:08 INFO - mozversion platform_version: 52.0a1 04:54:08 INFO - using testdate: 1478696048 04:54:08 INFO - actual date: 1478696048 04:54:08 INFO - starting webserver on 'localhost:62722' 04:54:08 INFO - SUITE-START | Running 2 tests 04:54:08 INFO - TEST-START | dromaeo_css 04:54:08 INFO - operating with platform_type : mac_ 04:54:08 INFO - Initialising browser for dromaeo_css test... 04:54:08 INFO - TEST-INFO | started process 6728 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmp1NH0sd/profile http://localhost:62722/getInfo.html) 04:54:10 INFO - PROCESS | 6728 | __metrics Screen width/height:1600/1200 04:54:10 INFO - PROCESS | 6728 | colorDepth:24 04:54:10 INFO - PROCESS | 6728 | Browser inner width/height: 1024/689 04:54:10 INFO - PROCESS | 6728 | __metrics 04:54:10 INFO - PROCESS | 6728 | 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" 04:54:10 INFO - PROCESS | 6728 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:54:11 INFO - TEST-INFO | 6728: exit 0 04:54:11 INFO - Browser initialized. 04:54:11 INFO - Running cycle 1/1 for dromaeo_css test... 04:54:11 INFO - TEST-INFO | started process 6730 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmp1NH0sd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:54:23 INFO - PROCESS | 6730 | RSS: Main: 239108096 04:54:23 INFO - PROCESS | 6730 | 04:55:14 INFO - PROCESS | 6730 | 1478696114791 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] 04:57:27 INFO - PROCESS | 6730 | Cycle 1(1): loaded http://localhost:62722/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62722/tests/dromaeo/cssquery-ext.html) 04:57:29 INFO - PROCESS | 6730 | RSS: Main: 409268224 04:57:29 INFO - PROCESS | 6730 | 05:00:32 INFO - PROCESS | 6730 | Cycle 1(1): loaded http://localhost:62722/tests/dromaeo/cssquery-ext.html (next: http://localhost:62722/tests/dromaeo/cssquery-jquery.html) 05:00:33 INFO - PROCESS | 6730 | RSS: Main: 378224640 05:00:33 INFO - PROCESS | 6730 | 05:03:36 INFO - PROCESS | 6730 | Cycle 1(1): loaded http://localhost:62722/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62722/tests/dromaeo/cssquery-mootools.html) 05:03:36 INFO - PROCESS | 6730 | RSS: Main: 335781888 05:03:36 INFO - PROCESS | 6730 | 05:06:39 INFO - PROCESS | 6730 | Cycle 1(1): loaded http://localhost:62722/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62722/tests/dromaeo/cssquery-prototype.html) 05:06:39 INFO - PROCESS | 6730 | RSS: Main: 332267520 05:06:39 INFO - PROCESS | 6730 | 05:09:43 INFO - PROCESS | 6730 | Cycle 1(1): loaded http://localhost:62722/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62722/tests/dromaeo/cssquery-yui.html) 05:09:43 INFO - PROCESS | 6730 | RSS: Main: 324419584 05:09:43 INFO - PROCESS | 6730 | 05:12:47 INFO - PROCESS | 6730 | Cycle 1(1): loaded http://localhost:62722/tests/dromaeo/cssquery-yui.html (next: http://localhost:62722/tests/dromaeo/cssquery-dojo.html) 05:12:47 INFO - PROCESS | 6730 | RSS: Main: 382341120 05:12:47 INFO - PROCESS | 6730 | 05:12:47 INFO - PROCESS | 6730 | __start_tp_report 05:12:47 INFO - PROCESS | 6730 | _x_x_mozilla_page_load 05:12:47 INFO - PROCESS | 6730 | _x_x_mozilla_page_load_details 05:12:47 INFO - PROCESS | 6730 | |i|pagename|runs| 05:12:47 INFO - PROCESS | 6730 | |0;dojo.html;3284.9835750821258;3299.8020118792874;3317.071220058384;3335.6164041135276;3367.7979321240714;5537.501624853762;5631.690257035862;5632.986062508747;5743.052396354602;5765.45228203321;5603.551715862732;5664.376918538967;5666.546676265899;5668.433156684329;5671.432856714337;5121.334226550553;5147.202183661539;5159.587233021368;5183.911378610496;5192.143296356116;5189.273501709764;5208.947910520884;5248.0815857225025;5278.6568873023325;5297;4355.390245365636;5264.026155161288;5270.104082305998;5328.866778330554;5345.465453454663;627.6017153530772;5258.447862974409;5273.630845840793;5280.551153151987;5313;3122.7353103168452;5067.631739430333;5113.923291150636;5154.5103215194495;5174.534291913746;4947.356843610308;5608.775648974036;5618.381977982419;5635;5683.374828768832;6225.595336303126;6238.438540531329;6257.217847769029;6262.091996660504;6275.152854364648;4461.732296062247;6134.110553969694;6154.107654390134;6177.04255840341;6181.598196117238;5693.060644993571;5723.713814309268;5749.0801471764735;5791.391903850119;5792.189093526909;6281.497480201573;6297.842553936188;6299.464545513591;6303.212098487689;6311.084892690534;5932.58471906971;5968.820935371946;5979.521638268928;6035.698215089228;6094.116353128771;6113.354685976905;6171.475424588871;6188.566800323934;6226.221722284715;6235.158253635792;6174.783882564088;6194.163787888668;6196;6206.224221972254;6214.658193799384;6089.817305480843;6121.46297486165;6152.477039451696;6199.907001394983;6228.564000520011;4906.2640603908985;4921.434035085939;4940.604751619861;4943.184374578226;4974.676646017997;4388.934165987513;4396.560343965578;4396.670249731283;4399.758013309299;4441.333799929972;5665.575081868877;5692.544596432275;5735.11105778605;5737.139429085587;5737.311522617312;5960.85097872548;6107.735039974563;6112.807455516499;6145.569810113249;6205.410486003822;5724.141378793214;5739.16782066598;5741.081426971665;5749.54003679713;5776.220210271644;5385.138377859524;5394.838154855361;5398.082326004589;5405.837824865261;5417.214503896913;6189.226346706661;6212.347703491068;6269.49844012487;6271.62370257786;6287.7799277026;6134.742747059018;6176.969115154575;6177.505799535937;6190.628562286277;6273.309935906956;6248.718807653576;6251.874962500816;6270.2475702915945;6277.403646653561;6319.715612797343;6025.758969641341;6130.356312587114;6137.079438084323;6158.291796443287;6161.969190154021;6014.939850601613;6136.048912418582;6144.310314663503;6197.411245931629;6239.282482514569;6089.1779609753885;6108.908366374597;6111.205543279345;6137.018077107641;6137.7851775187655;5941.554383421174;6063.757449701962;6126.632402055891;6136.447719705248;6144.262688477412;5747.885042299215;5786.566007549366;5834.766609335748;5861.443162899518;5887.676177810262;5991.310999234969;6056;6065.696715164136;6091.177691011657;6109.419605137505;5757.683327417032;5799.623024503394;5800.854978625568;5821.825345239649;5840.707964601838 05:12:47 INFO - PROCESS | 6730 | |1;ext.html;473.54303097510893;500.3045766384725;507.5357593343204;518.1088527732302;547.5646860745709;25261;38267.043323916914;39064.41403378948;39088;41599.58400415995;40703.59296407035;40928.59071409285;41073;41114.79442602783;41190.794046029805;8367.577511822974;9225.354225204239;9419.85870211944;9431.528423578828;9432.622695092188;8363.121872203423;8974.730758077236;11241;11242;11438.771224575503;11178.155604325277;11232.887671123266;11240.437978101103;11245.100391968623;11249.775004499905;8286.502809831369;8548.145185481464;11176.105911527058;11254.943725281362;11286.209965302432;8419.494830310201;8492.872606910902;8502.872456913152;10655.041046305869;10996.010359067643;39112.608873911464;39120;39274.60725392738;39346.016349591206;39354.803225983684;3066.23344163959;3109.54911537828;3342.632310445849;3367.44437167867;3624.27514497099;27562.5865612016;29994.550081748574;30142.54786178209;31277.687223127705;31284.53073203904;3094.814311141316;3113.128324069264;3278.459054256045;3317.883874064396;3324.202191474029;475580;476212;478556.21443785814;479493.6025319886;482274;53888;56290.718546407006;56315;56425.43574564284;56431.43568564344;58290.542736431926;58955.410445894995;59009.819803603685;59014.11478827821;59147.704261478415;15238.857085718615;15620.76568851468;15625.453109141354;15650.608734781721;15680.372785088697;11000.119990400748;11212.607558735404;11216.719582010515;12138.968187703967;12264.693382665504;14676.412943482355;14744.55766327012;14972.850271497146;15061.322240499203;15110.320035598425;5990.400959903975;6910.170779506391;6954.79135625932;6988.580685158805;7006.684699188549;862.2067697717989;949.9952500237592;950.6577632052456;976.2482888176065;988.2637435110745;25889.093881714427;27501.174964751088;29102.417951640848;29379.265518362212;29731.702682972893;1018.1752780248023;1042.588177669816;1064.223117124488;1086.2613422872523;1107.4684151607273;14975.400983960737;14977.326020329112;14988.550343489713;15358.616034599225;15486.148261845707;619373;690075;690983.6270466192;691085;889297;54503;55153.72423137858;55264.17103743447;55332.72333638306;55365.44634553683;1211.6607349941853;1241.5778635264053;1320.2804471563022;1342.0069148829991;1364.4269406849148;23695.490541464253;24310.75689243085;30408.695913041473;30568.93008744683;32131.878458285566;50475.74762126166;50914.49085509246;50978;51062.74468627633;51194.74402627963;565192;570508;571036;571584;571848;22282.77717222807;22884.199053033062;23277.254205934285;24485.877570612032;24962.750372496044;36822.63177368192;37266.8136659315;37304.253914921006;38817;38885.8055709731;33577.83211083929;35588.82205589059;35689.64310356863;36295;37311.253774924895;35071.47392789161;35939.46090808692;36008;36591.26817463689;36878.63121368752;35863.82068089747;36085.45871811977;36159;36864.44703329399;36989.445158323186;29319.560206596492;33027.75210451435;33502.162445939044;33612;33921.830390847885;22700;24402.51194976126;24801.62797558002;24872.502549949262;25123.497530049663 05:12:47 INFO - PROCESS | 6730 | |2;jquery.html;368.55036855036855;372.9925401491968;382.1802234207624;394.044442227598;404.38331544394794;15209.239538023083;15313.693726125472;18277.26890924363;20687.482812929666;20818.063187156575;17939.282428702867;19420.320288789895;20345.49136271594;20362.389128326136;20365.18539258428;5672.574556908227;5955.285365756094;5961.88076238475;5961.940380596204;5973.372795856438;4147.5068975168915;4389.297712366006;5800.941990580082;5910.113482977545;6162.815115546518;6357.936420635804;6534.705938232791;6535.901961470582;6536.934630653704;6543.1821022372205;6202.85247229264;6411.166548348731;6482.513811464112;6492.545521813475;6531.4448271896945;5433.212184233304;5493.615446918718;5624.21261023457;5624.268845050158;5634.971825140889;24481.387965300833;24485;24700.5059898801;24807;24837.882295296575;1865.0301843041677;2038.0828627117855;2106.3996760923064;2310.237621584873;2570.7043689975703;9232.8615070774;9582.329236953416;9698.757531061709;9799.412035277906;9993.600255989752;1642.3266460751036;1708.8034875989297;1709.2479309104078;1718.4243278501717;1775.3874913154941;113845;119596;122665.38667306695;122684;123167.15249271267;8504.702335418231;9991.400515969066;9999.900010998785;10165.898341016495;10699.090577300862;12765.93617031909;13606.251656159006;14063;14088;14158.292085395895;6355.872882542416;6533.314002029813;7162.493127405471;8051.919480805117;8216.178382161735;8012.0786109596975;8060.153683863227;8091.716789912325;8246.793830154294;8256.876146857801;4333.068390296102;4355.73865568067;4450.017210011284;4455.844045458393;4504.819807207675;1757.534253422844;1786.0948063772526;1870.4856164554856;1921.4619906426224;1945.6497830390665;757.9469437139347;791.2404092071561;858.9785255368665;898.2589363774912;908.8773015642804;19176.616467670567;20481.48796280105;20616.793832061787;20686.275980340917;20780.896095519427;752.9887051694229;842.389267780854;905.1265528764774;909.6225066597289;940.1022023967095;10491.895081049091;10526.889606914163;10980.780384392267;11035.62054743157;11049.447527623586;542531;574157;578226;591615;594580;130742.34628826795;132612;134223.65776342113;134554.3272283652;134791;919.7562645898843;933.6778811310087;943.4527973775331;955.8948515663271;979.7795496013482;14064.437422503395;18499;19225.903870480557;19268.614627707648;19339.90330048341;52356.47643523516;54690.45309546853;56071.158932615224;56276.71861640666;56465;75633;75893;76310.09224769425;76416;79320;7832.647530861239;7908.327792137503;7947.284744373034;8044.3564514839845;8320.875186872323;11702.085243809825;12368.19606725596;12480.068778334795;12623.368831558202;12732.172408793398;10354.948225258826;10636.255462117577;10972.067374272958;10985.01134897831;11034.668959931216;9578.80264966879;10076.345037572535;10401.895981040396;10556.788864222826;10997.890021099687;8947.507887066275;10092.788865336206;10817.783644326912;10874;10890.23768336209;7895.171007044178;7964.2433968772875;7977.521348719096;8002.59987000636;8042.622098718435;9877.654282100091;10668.46657667098;10852.40311782828;10859.34843909368;10979.28634638746 05:12:47 INFO - PROCESS | 6730 | |3;mootools.html;1495.2822645130336;1516.3328135620327;1666.5916850371661;1819.490542648059;1828.5154434074964;7919.8416031679335;8447.15528447155;8450.95774521128;8459.49243045417;8480.915190848098;7370.8894366584545;7628.199039100898;7631;7990.560519171443;8211.91788082119;1903.7620297462818;2322.628379459287;2322.674825524428;2326.081197926818;2335.8481698689616;2300.5053913408615;2321.129576408847;2330.7902288794007;2353.8587684738886;2355.1168311883043;2298.333483289844;2299.011425087212;2328.953420931589;2330.2310237621627;2330.3242059802637;2289.5993201189726;2296.59809533331;2316.7527498326763;2319.3273950554235;2323.802476789475;2142.6143294207036;2334.136369543263;2341.7189937207645;2344.788968992799;2349.7415284318713;8311.127331630152;8397.034341050736;8444.451110677848;8484.066752657203;8491.23578877904;5036.471170527115;5220.086484865134;5221.138512145491;5230.372355317349;5244.0036393085575;3043.3761078978755;3219.951700724491;3388.203772113551;3578.4095624221973;3587.982060089683;1044.2742294105599;1049.3861091261622;1074.006543946846;1100.0759362135764;1104.729341311384;10451.790964180826;10503;10814.134869210444;10906.563737450493;11118.777624447466;4258.5954334338185;4361.847335343247;4443.311286750559;4489.2592722201125;4491.708038977521;8651.567421628893;8669;8681.826363472821;8686.56567171639;8724.214820666171;4998.275250088789;5095.617828662865;5099.719515426613;5109.489051094935;5125.615578831603;4254.851080212177;4391.560843915583;4696.600788932979;4782.187028205213;4794.041191761662;3546.9645303546636;3553.662402071799;3608.657177568127;3639.4176931690804;3646;1066.6160182334354;1121.0023079459288;1122.5944404846718;1141.7031571791395;1143.4854315558032;792.3740245206222;838.0278876503227;882.6866462405882;891.2023738753779;925.5603588295505;5299.947000529945;5379.112446446371;5628.859278517988;5738.512226460798;5763.049096898964;926.5228407370209;945.2485274207022;966.1063516247583;1003.9146672532917;1039.8336266197373;3722.7207959403154;3728.6644202021407;3739.8317075731657;3854.6338097880657;3864.942025869614;8844;10310.32982856112;10314.27800054004;10341.689749307532;10349.637762678421;10189.082982531607;10275;10278.331908426078;10278.640247591298;10302.536385862653;7381.741639042715;7804.141544430108;7947.5231486111015;7968.880466793003;7975.082865470429;5175.120229561059;5591.720413979366;5697.57268204878;5730.5702072345575;5828.533717302521;8964.058773828654;9124.589393477178;9366.250699944125;9494.905050949403;9501.572429240563;9244.768880778034;9370.062993700576;9571.138597526256;9676.612935482783;9686.32195746291;7113.430925525842;7339.412846972123;7349.1915889253205;7634.847303054019;7850.293473587404;7467.626618669154;7678.078630564145;7956.9602151988865;7983.2415920487465;8103.959480202561;5947.286325640778;7193.928060719326;7235.312645298688;7248.347648711641;7384.37232835215;7025.578465292099;7034.964825175842;7047.048648432396;7056.6118863463;7161.57030578167;6686.197656281277;6707.463402927851;6755.256921738703;6776.254611992578;6790.8641827164165;4775.403074615673;4851.544536639065;4858.684185527929;4866.635002374906;4957.3059771632725;4700.918788678691;4826.734529600905;4886.242632391985;4920.532549407801;4935 05:12:47 INFO - PROCESS | 6730 | |4;prototype.html;274.6018273503419;298.7027907232304;301.55972280470513;304.74705994024964;317.30668489350757;4816.638752093599;5299.947000529994;5326.813561525348;5376.865578360543;5404.675719456835;4395.494518130416;4518.751468669222;4805.423349198092;4835.823879254332;5099.184130539114;3491.703205227546;3843.7501562398493;3947.4868267125307;4033.495813023372;4052;3326.534285200074;4018.3972404139467;4030.5364883038533;4037.9394309085387;4043.5552089270304;3843.61629813267;3910.315694753421;4019.1157945252;4036.3541833306676;4045.656119229869;3671.541057367829;4001.3197756381487;4019.879403617896;4036.777977211252;4043.777592232426;3507.8421471033867;3507.8947631571095;3513.2622149348667;3515.33208690348;3526.400511912981;4657.534246575349;4734.360861283752;4812.109759694433;5196.870078248074;5218.347706536683;4327.870163895088;4560.338750881104;4603.171429142753;4617.284320930261;4642.303654451825;4692.826793301675;5079.958608485252;5113.642045056848;5388.595855310868;5530.893821235729;3326.96673033269;3792.1846802937503;3837.597052309523;3841.5006049213425;3854.1906199698096;4864.464908860023;5568.721563921828;5798.608333999852;5845.649261044351;5852.795152169696;3887.922241555153;3889.4554762333296;3890.9416358754643;3894.6105389460827;3900.278448487039;3618.805794087945;3687.6399104573093;3912.628300311466;3944.15200731844;3950.1309711863346;3793.525809273841;4224.028473451062;4317.136572685476;4533.750643714561;4591.816327346869;5593.356763972195;5614.9438505615235;5616.522595579421;5620.662760234399;5635.830925072254;1094.5074716377662;1206.818977153434;1272.287518989369;1278.6931136527353;1280.8408390406696;753.8428512233804;826.2811354121952;836.4024712798525;846.2552953400967;846.7502086884384;4485.641148708096;5290.603204759658;5310.787568497217;5321.5476684482255;5366.91949620756;5196.870078248074;5345.224942383333;5533.612647114664;5759.625624334405;5811.535077193815;4530.478994915561;4661.370714953506;4947.332110165082;5128.1026020371955;5320.388155362183;2374.0414158319454;2557.889740963675;2780.860956952144;2861.3561948561405;2878.4387044526115;4815.542523460265;5301.595077304517;5340.513968266675;5647.261078376984;5699.914501282484;4957.553820156131;5534.75093620788;5586.636868603528;5652.943470565242;5685.630434021776;4655.837045703384;5228.4510126436935;5243.030039442715;5592.972035139798;5620.943790562205;4607.654425918003;4989.106318335284;5026.447090819934;5367.463253674601;5429.972850135882;4509.481409637933;5265.289185959847;5419.295491586068;5713.05734553794;5781;5261.73691315425;6015.338312785678;6031.004884194059;6032.788852390146;6034.758609655564;3327.885158471939;3689.7786132832116;3812.8076356094803;3845.942310865283;3923.843046278117;5024.874378140429;5299.364076310867;5518.44815518461;5568.888622227614;5829.970850145722;5660.462256085665;5661.943380566142;5666.46168613981;5676.886462270649;5698.943010570007;5401.207680168863;5621.325440947113;5651.982643124277;5701.7149142541875;5726.856828579319;5289.761960711854;5495;5508.476694713996;5582.553395728413;5601.831945041656;4741.846671963244;4862.346802087307;4909.723471897261;5371.301730774974;5592.86017849557;4848.3454733610515;5041.924371134362;5497.45025497447;5539.7784088636;5555.916661250165 05:12:47 INFO - PROCESS | 6730 | |5;yui.html;705.8235441139715;722.5484072454713;761.2805898425989;793.9285464308211;808.5997431271521;13277.601671949846;13528.120672156327;13762.724745505084;13806.792898106523;13855.515056972996;12406.751864962695;12464.875351246485;12472.563460278892;12510.749785004316;12515.93614542765;678.375894177358;701.4003027411566;820.2781552234026;830.4685001598981;849.2823564088335;838.6854929401475;845.2012847858776;852.0669866496177;857.2327766648846;858.7767180533075;899.6311512279966;905.2124651553158;906.7869050773063;908.1100521488945;910.5629297936994;715.2597062040815;901.779064129286;902.1700035966926;902.6118768929391;902.9187373136449;552.9281193444866;647.1037612906111;673.4040374268808;676.6853413162903;682.8634273145391;11300.604478843201;11652.708682282926;12341.444634991358;12386.81419778704;12388.56640017595;11512.769744605144;11591.942040289745;11619.709507262301;11787.587434439753;12084.758304833855;10135.949320253425;11381.487833047533;11411.48648310828;11471.139664525193;11551.133664975076;3755.6244375562496;3786.6024067472795;3848.0956975110826;3998.140399814054;4000.439938408625;79391.603041985;81480;82546.17453825445;84025.15974840235;84156.15843841544;8688.739337819876;8762.255208307237;8897.243734282529;9060.184583387527;9246.306527010502;17530.47408577745;17791.19939602721;17959.191836367394;17992.37026704059;18004.459866204033;6684.362331228783;6731;6747.628880411527;6754.310944927294;6871.793846184622;3714.220013797079;3748.868789592406;3768.5477742671055;3787.110029143149;3796.2976849282973;3070.6008218931397;3128.6089238845143;3184.7770656054317;3188.505781603855;3195.2171717929255;584.8830233953227;600.4205941266698;608.4767100293744;617.5800455690083;619.5508256514081;587.8089620873147;603.1616053685381;619.4610688700859;620.906863970408;628.3590737447778;6806.591604503746;7412.481126321213;8004;8042.879356809653;8157.632906519221;515.2760371677799;553.7867920850421;561.943805619443;564.7232855900661;624.7313655128247;3166.9683303167135;3876.8449262029767;3944.7633142010995;4012.378081397388;4027;90332;101526.96946061037;101655.96688066197;103689.48155259175;105209;98552;101423;101458.49270753599;103373;104170.47914760378;86477;88260;89521.55239223762;90639.09360906307;90971.54514227387;7599;7611.809704757426;7738.92261077382;7792.1428642848105;7971.1630278819885;38822.80588597039;48002.759986199846;48616.75691621519;51079.956801727516;52267.95464090773;65245.347546523924;69891.65054174696;75438.62280688781;76536.70389888089;77445.61277193578;9974.55114519863;10013.799724005625;10090.394576325443;10106.242031847494;10374.42940638242;11299.774004520028;11630.592929247436;11641.708957275798;11711.941440292743;12027.037836973197;10569;10772.784544309226;10776.407297598395;10874.565017399216;11222.18671353805;10660.573577056832;11638.185327027028;11931.284122952304;12151.149419540547;12176.39118044112;11822.11334149925;11881.168318217642;11928.76142477163;12402.441890114786;12492.937535312265;9996.800063998533;10044.748881278027;10111.59553617847;10428.53071611764;10752;7964.561949092856;9390.859137112811;9735.41587504752;9998.05018523239;10016.098551130433 05:12:47 INFO - PROCESS | 6730 | __end_tp_report 05:12:47 INFO - PROCESS | 6730 | __start_cc_report 05:12:47 INFO - PROCESS | 6730 | _x_x_mozilla_cycle_collect,2152 05:12:47 INFO - PROCESS | 6730 | __end_cc_report 05:12:47 INFO - PROCESS | 6730 | __startTimestamp1478697167370__endTimestamp 05:12:47 INFO - PROCESS | 6730 | 05:12:47 INFO - PROCESS | 6730 | ------- Summary: start ------- 05:12:47 INFO - PROCESS | 6730 | Number of tests: 6 05:12:47 INFO - PROCESS | 6730 | 05:12:47 INFO - PROCESS | 6730 | [#0] dojo.html Cycles:165 Average:5653.82 Median:5840.71 stddev:776.90 (13.3%) stddev-sans-first:756.73 05:12:47 INFO - PROCESS | 6730 | Values: 3285.0 3299.8 3317.1 3335.6 3367.8 5537.5 5631.7 5633.0 5743.1 5765.5 5603.6 5664.4 5666.5 5668.4 5671.4 5121.3 5147.2 5159.6 5183.9 5192.1 5189.3 5208.9 5248.1 5278.7 5297.0 4355.4 5264.0 5270.1 5328.9 5345.5 627.6 5258.4 5273.6 5280.6 5313.0 3122.7 5067.6 5113.9 5154.5 5174.5 4947.4 5608.8 5618.4 5635.0 5683.4 6225.6 6238.4 6257.2 6262.1 6275.2 4461.7 6134.1 6154.1 6177.0 6181.6 5693.1 5723.7 5749.1 5791.4 5792.2 6281.5 6297.8 6299.5 6303.2 6311.1 5932.6 5968.8 5979.5 6035.7 6094.1 6113.4 6171.5 6188.6 6226.2 6235.2 6174.8 6194.2 6196.0 6206.2 6214.7 6089.8 6121.5 6152.5 6199.9 6228.6 4906.3 4921.4 4940.6 4943.2 4974.7 4388.9 4396.6 4396.7 4399.8 4441.3 5665.6 5692.5 5735.1 5737.1 5737.3 5960.9 6107.7 6112.8 6145.6 6205.4 5724.1 5739.2 5741.1 5749.5 5776.2 5385.1 5394.8 5398.1 5405.8 5417.2 6189.2 6212.3 6269.5 6271.6 6287.8 6134.7 6177.0 6177.5 6190.6 6273.3 6248.7 6251.9 6270.2 6277.4 6319.7 6025.8 6130.4 6137.1 6158.3 6162.0 6014.9 6136.0 6144.3 6197.4 6239.3 6089.2 6108.9 6111.2 6137.0 6137.8 5941.6 6063.8 6126.6 6136.4 6144.3 5747.9 5786.6 5834.8 5861.4 5887.7 5991.3 6056.0 6065.7 6091.2 6109.4 5757.7 5799.6 5800.9 5821.8 5840.7 05:12:47 INFO - PROCESS | 6730 | 05:12:47 INFO - PROCESS | 6730 | [#1] ext.html Cycles:180 Average:70556.89 Median:36122.23 stddev:160661.55 (444.8%) stddev-sans-first:161026.08 05:12:47 INFO - PROCESS | 6730 | Values: 473.5 500.3 507.5 518.1 547.6 25261.0 38267.0 39064.4 39088.0 41599.6 40703.6 40928.6 41073.0 41114.8 41190.8 8367.6 9225.4 9419.9 9431.5 9432.6 8363.1 8974.7 11241.0 11242.0 11438.8 11178.2 11232.9 11240.4 11245.1 11249.8 8286.5 8548.1 11176.1 11254.9 11286.2 8419.5 8492.9 8502.9 10655.0 10996.0 39112.6 39120.0 39274.6 39346.0 39354.8 3066.2 3109.5 3342.6 3367.4 3624.3 27562.6 29994.6 30142.5 31277.7 31284.5 3094.8 3113.1 3278.5 3317.9 3324.2 475580.0 476212.0 478556.2 479493.6 482274.0 53888.0 56290.7 56315.0 56425.4 56431.4 58290.5 58955.4 59009.8 59014.1 59147.7 15238.9 15620.8 15625.5 15650.6 15680.4 11000.1 11212.6 11216.7 12139.0 12264.7 14676.4 14744.6 14972.9 15061.3 15110.3 5990.4 6910.2 6954.8 6988.6 7006.7 862.2 950.0 950.7 976.2 988.3 25889.1 27501.2 29102.4 29379.3 29731.7 1018.2 1042.6 1064.2 1086.3 1107.5 14975.4 14977.3 14988.6 15358.6 15486.1 619373.0 690075.0 690983.6 691085.0 889297.0 54503.0 55153.7 55264.2 55332.7 55365.4 1211.7 1241.6 1320.3 1342.0 1364.4 23695.5 24310.8 30408.7 30568.9 32131.9 50475.7 50914.5 50978.0 51062.7 51194.7 565192.0 570508.0 571036.0 571584.0 571848.0 22282.8 22884.2 23277.3 24485.9 24962.8 36822.6 37266.8 37304.3 38817.0 38885.8 33577.8 35588.8 35689.6 36295.0 37311.3 35071.5 35939.5 36008.0 36591.3 36878.6 35863.8 36085.5 36159.0 36864.4 36989.4 29319.6 33027.8 33502.2 33612.0 33921.8 22700.0 24402.5 24801.6 24872.5 25123.5 05:12:47 INFO - PROCESS | 6730 | 05:12:47 INFO - PROCESS | 6730 | [#2] jquery.html Cycles:180 Average:34320.83 Median:4344.40 stddev:96710.02 (2226.1%) stddev-sans-first:96947.72 05:12:47 INFO - PROCESS | 6730 | Values: 368.6 373.0 382.2 394.0 404.4 15209.2 15313.7 18277.3 20687.5 20818.1 17939.3 19420.3 20345.5 20362.4 20365.2 5672.6 5955.3 5961.9 5961.9 5973.4 4147.5 4389.3 5800.9 5910.1 6162.8 6357.9 6534.7 6535.9 6536.9 6543.2 6202.9 6411.2 6482.5 6492.5 6531.4 5433.2 5493.6 5624.2 5624.3 5635.0 24481.4 24485.0 24700.5 24807.0 24837.9 1865.0 2038.1 2106.4 2310.2 2570.7 9232.9 9582.3 9698.8 9799.4 9993.6 1642.3 1708.8 1709.2 1718.4 1775.4 113845.0 119596.0 122665.4 122684.0 123167.2 8504.7 9991.4 9999.9 10165.9 10699.1 12765.9 13606.3 14063.0 14088.0 14158.3 6355.9 6533.3 7162.5 8051.9 8216.2 8012.1 8060.2 8091.7 8246.8 8256.9 4333.1 4355.7 4450.0 4455.8 4504.8 1757.5 1786.1 1870.5 1921.5 1945.6 757.9 791.2 859.0 898.3 908.9 19176.6 20481.5 20616.8 20686.3 20780.9 753.0 842.4 905.1 909.6 940.1 10491.9 10526.9 10980.8 11035.6 11049.4 542531.0 574157.0 578226.0 591615.0 594580.0 130742.3 132612.0 134223.7 134554.3 134791.0 919.8 933.7 943.5 955.9 979.8 14064.4 18499.0 19225.9 19268.6 19339.9 52356.5 54690.5 56071.2 56276.7 56465.0 75633.0 75893.0 76310.1 76416.0 79320.0 7832.6 7908.3 7947.3 8044.4 8320.9 11702.1 12368.2 12480.1 12623.4 12732.2 10354.9 10636.3 10972.1 10985.0 11034.7 9578.8 10076.3 10401.9 10556.8 10997.9 8947.5 10092.8 10817.8 10874.0 10890.2 7895.2 7964.2 7977.5 8002.6 8042.6 9877.7 10668.5 10852.4 10859.3 10979.3 05:12:47 INFO - PROCESS | 6730 | 05:12:47 INFO - PROCESS | 6730 | [#3] mootools.html Cycles:180 Average:5419.55 Median:4977.79 stddev:2971.97 (59.7%) stddev-sans-first:2965.68 05:12:47 INFO - PROCESS | 6730 | Values: 1495.3 1516.3 1666.6 1819.5 1828.5 7919.8 8447.2 8451.0 8459.5 8480.9 7370.9 7628.2 7631.0 7990.6 8211.9 1903.8 2322.6 2322.7 2326.1 2335.8 2300.5 2321.1 2330.8 2353.9 2355.1 2298.3 2299.0 2329.0 2330.2 2330.3 2289.6 2296.6 2316.8 2319.3 2323.8 2142.6 2334.1 2341.7 2344.8 2349.7 8311.1 8397.0 8444.5 8484.1 8491.2 5036.5 5220.1 5221.1 5230.4 5244.0 3043.4 3220.0 3388.2 3578.4 3588.0 1044.3 1049.4 1074.0 1100.1 1104.7 10451.8 10503.0 10814.1 10906.6 11118.8 4258.6 4361.8 4443.3 4489.3 4491.7 8651.6 8669.0 8681.8 8686.6 8724.2 4998.3 5095.6 5099.7 5109.5 5125.6 4254.9 4391.6 4696.6 4782.2 4794.0 3547.0 3553.7 3608.7 3639.4 3646.0 1066.6 1121.0 1122.6 1141.7 1143.5 792.4 838.0 882.7 891.2 925.6 5299.9 5379.1 5628.9 5738.5 5763.0 926.5 945.2 966.1 1003.9 1039.8 3722.7 3728.7 3739.8 3854.6 3864.9 8844.0 10310.3 10314.3 10341.7 10349.6 10189.1 10275.0 10278.3 10278.6 10302.5 7381.7 7804.1 7947.5 7968.9 7975.1 5175.1 5591.7 5697.6 5730.6 5828.5 8964.1 9124.6 9366.3 9494.9 9501.6 9244.8 9370.1 9571.1 9676.6 9686.3 7113.4 7339.4 7349.2 7634.8 7850.3 7467.6 7678.1 7957.0 7983.2 8104.0 5947.3 7193.9 7235.3 7248.3 7384.4 7025.6 7035.0 7047.0 7056.6 7161.6 6686.2 6707.5 6755.3 6776.3 6790.9 4775.4 4851.5 4858.7 4866.6 4957.3 4700.9 4826.7 4886.2 4920.5 4935.0 05:12:47 INFO - PROCESS | 6730 | 05:12:47 INFO - PROCESS | 6730 | [#4] prototype.html Cycles:180 Average:4448.78 Median:4928.53 stddev:1373.34 (27.9%) stddev-sans-first:1340.98 05:12:47 INFO - PROCESS | 6730 | Values: 274.6 298.7 301.6 304.7 317.3 4816.6 5299.9 5326.8 5376.9 5404.7 4395.5 4518.8 4805.4 4835.8 5099.2 3491.7 3843.8 3947.5 4033.5 4052.0 3326.5 4018.4 4030.5 4037.9 4043.6 3843.6 3910.3 4019.1 4036.4 4045.7 3671.5 4001.3 4019.9 4036.8 4043.8 3507.8 3507.9 3513.3 3515.3 3526.4 4657.5 4734.4 4812.1 5196.9 5218.3 4327.9 4560.3 4603.2 4617.3 4642.3 4692.8 5080.0 5113.6 5388.6 5530.9 3327.0 3792.2 3837.6 3841.5 3854.2 4864.5 5568.7 5798.6 5845.6 5852.8 3887.9 3889.5 3890.9 3894.6 3900.3 3618.8 3687.6 3912.6 3944.2 3950.1 3793.5 4224.0 4317.1 4533.8 4591.8 5593.4 5614.9 5616.5 5620.7 5635.8 1094.5 1206.8 1272.3 1278.7 1280.8 753.8 826.3 836.4 846.3 846.8 4485.6 5290.6 5310.8 5321.5 5366.9 5196.9 5345.2 5533.6 5759.6 5811.5 4530.5 4661.4 4947.3 5128.1 5320.4 2374.0 2557.9 2780.9 2861.4 2878.4 4815.5 5301.6 5340.5 5647.3 5699.9 4957.6 5534.8 5586.6 5652.9 5685.6 4655.8 5228.5 5243.0 5593.0 5620.9 4607.7 4989.1 5026.4 5367.5 5430.0 4509.5 5265.3 5419.3 5713.1 5781.0 5261.7 6015.3 6031.0 6032.8 6034.8 3327.9 3689.8 3812.8 3845.9 3923.8 5024.9 5299.4 5518.4 5568.9 5830.0 5660.5 5661.9 5666.5 5676.9 5698.9 5401.2 5621.3 5652.0 5701.7 5726.9 5289.8 5495.0 5508.5 5582.6 5601.8 4741.8 4862.3 4909.7 5371.3 5592.9 4848.3 5041.9 5497.5 5539.8 5555.9 05:12:47 INFO - PROCESS | 6730 | 05:12:47 INFO - PROCESS | 6730 | [#5] yui.html Cycles:180 Average:19493.42 Median:25797.62 stddev:29612.25 (114.8%) stddev-sans-first:29661.72 05:12:47 INFO - PROCESS | 6730 | Values: 705.8 722.5 761.3 793.9 808.6 13277.6 13528.1 13762.7 13806.8 13855.5 12406.8 12464.9 12472.6 12510.7 12515.9 678.4 701.4 820.3 830.5 849.3 838.7 845.2 852.1 857.2 858.8 899.6 905.2 906.8 908.1 910.6 715.3 901.8 902.2 902.6 902.9 552.9 647.1 673.4 676.7 682.9 11300.6 11652.7 12341.4 12386.8 12388.6 11512.8 11591.9 11619.7 11787.6 12084.8 10135.9 11381.5 11411.5 11471.1 11551.1 3755.6 3786.6 3848.1 3998.1 4000.4 79391.6 81480.0 82546.2 84025.2 84156.2 8688.7 8762.3 8897.2 9060.2 9246.3 17530.5 17791.2 17959.2 17992.4 18004.5 6684.4 6731.0 6747.6 6754.3 6871.8 3714.2 3748.9 3768.5 3787.1 3796.3 3070.6 3128.6 3184.8 3188.5 3195.2 584.9 600.4 608.5 617.6 619.6 587.8 603.2 619.5 620.9 628.4 6806.6 7412.5 8004.0 8042.9 8157.6 515.3 553.8 561.9 564.7 624.7 3167.0 3876.8 3944.8 4012.4 4027.0 90332.0 101527.0 101656.0 103689.5 105209.0 98552.0 101423.0 101458.5 103373.0 104170.5 86477.0 88260.0 89521.6 90639.1 90971.5 7599.0 7611.8 7738.9 7792.1 7971.2 38822.8 48002.8 48616.8 51080.0 52268.0 65245.3 69891.7 75438.6 76536.7 77445.6 9974.6 10013.8 10090.4 10106.2 10374.4 11299.8 11630.6 11641.7 11711.9 12027.0 10569.0 10772.8 10776.4 10874.6 11222.2 10660.6 11638.2 11931.3 12151.1 12176.4 11822.1 11881.2 11928.8 12402.4 12492.9 9996.8 10044.7 10111.6 10428.5 10752.0 7964.6 9390.9 9735.4 9998.1 10016.1 05:12:47 INFO - PROCESS | 6730 | -------- Summary: end -------- 05:12:47 INFO - PROCESS | 6730 | 05:12:48 INFO - TEST-INFO | 6730: exit 0 05:12:48 INFO - TEST-OK | dromaeo_css | took 1119718ms 05:12:48 INFO - TEST-START | kraken 05:12:48 INFO - operating with platform_type : mac_ 05:12:48 INFO - Initialising browser for kraken test... 05:12:48 INFO - TEST-INFO | started process 6732 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpX1nJMR/profile http://localhost:62722/getInfo.html) 05:12:50 INFO - PROCESS | 6732 | __metrics Screen width/height:1600/1200 05:12:50 INFO - PROCESS | 6732 | colorDepth:24 05:12:50 INFO - PROCESS | 6732 | Browser inner width/height: 1024/689 05:12:50 INFO - PROCESS | 6732 | __metrics 05:12:50 INFO - PROCESS | 6732 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:12:50 INFO - PROCESS | 6732 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:12:51 INFO - TEST-INFO | 6732: exit 0 05:12:51 INFO - Browser initialized. 05:12:51 INFO - Running cycle 1/1 for kraken test... 05:12:51 INFO - TEST-INFO | started process 6733 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpX1nJMR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:13:03 INFO - PROCESS | 6733 | RSS: Main: 239427584 05:13:03 INFO - PROCESS | 6733 | 05:13:06 INFO - PROCESS | 6733 | Cycle 1(1): loaded http://localhost:62722/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62722/tests/kraken/driver.html?testName=audio-beat-detection) 05:13:07 INFO - PROCESS | 6733 | RSS: Main: 290168832 05:13:07 INFO - PROCESS | 6733 | 05:13:09 INFO - PROCESS | 6733 | Cycle 1(1): loaded http://localhost:62722/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62722/tests/kraken/driver.html?testName=audio-dft) 05:13:10 INFO - PROCESS | 6733 | RSS: Main: 290992128 05:13:10 INFO - PROCESS | 6733 | 05:13:14 INFO - PROCESS | 6733 | Cycle 1(1): loaded http://localhost:62722/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62722/tests/kraken/driver.html?testName=audio-fft) 05:13:14 INFO - PROCESS | 6733 | RSS: Main: 381898752 05:13:14 INFO - PROCESS | 6733 | 05:13:16 INFO - PROCESS | 6733 | Cycle 1(1): loaded http://localhost:62722/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62722/tests/kraken/driver.html?testName=audio-oscillator) 05:13:17 INFO - PROCESS | 6733 | RSS: Main: 282857472 05:13:17 INFO - PROCESS | 6733 | 05:13:19 INFO - PROCESS | 6733 | Cycle 1(1): loaded http://localhost:62722/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62722/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:13:20 INFO - PROCESS | 6733 | RSS: Main: 288747520 05:13:20 INFO - PROCESS | 6733 | 05:13:22 INFO - PROCESS | 6733 | Cycle 1(1): loaded http://localhost:62722/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62722/tests/kraken/driver.html?testName=imaging-darkroom) 05:13:23 INFO - PROCESS | 6733 | RSS: Main: 327262208 05:13:23 INFO - PROCESS | 6733 | 05:13:26 INFO - PROCESS | 6733 | Cycle 1(1): loaded http://localhost:62722/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62722/tests/kraken/driver.html?testName=imaging-desaturate) 05:13:26 INFO - PROCESS | 6733 | RSS: Main: 325804032 05:13:26 INFO - PROCESS | 6733 | 05:13:29 INFO - PROCESS | 6733 | Cycle 1(1): loaded http://localhost:62722/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62722/tests/kraken/driver.html?testName=json-parse-financial) 05:13:30 INFO - PROCESS | 6733 | RSS: Main: 326041600 05:13:30 INFO - PROCESS | 6733 | 05:13:31 INFO - PROCESS | 6733 | Cycle 1(1): loaded http://localhost:62722/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62722/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:13:32 INFO - PROCESS | 6733 | RSS: Main: 302579712 05:13:32 INFO - PROCESS | 6733 | 05:13:34 INFO - PROCESS | 6733 | Cycle 1(1): loaded http://localhost:62722/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62722/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:13:35 INFO - PROCESS | 6733 | RSS: Main: 303894528 05:13:35 INFO - PROCESS | 6733 | 05:13:37 INFO - PROCESS | 6733 | Cycle 1(1): loaded http://localhost:62722/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62722/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:13:38 INFO - PROCESS | 6733 | RSS: Main: 306802688 05:13:38 INFO - PROCESS | 6733 | 05:13:41 INFO - PROCESS | 6733 | Cycle 1(1): loaded http://localhost:62722/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62722/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:13:41 INFO - PROCESS | 6733 | RSS: Main: 320831488 05:13:41 INFO - PROCESS | 6733 | 05:13:44 INFO - PROCESS | 6733 | Cycle 1(1): loaded http://localhost:62722/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62722/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:13:45 INFO - PROCESS | 6733 | RSS: Main: 314597376 05:13:45 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | Cycle 1(1): loaded http://localhost:62722/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62722/tests/kraken/driver.html?testName=ai-astar) 05:13:47 INFO - PROCESS | 6733 | RSS: Main: 358060032 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | __start_tp_report 05:13:47 INFO - PROCESS | 6733 | _x_x_mozilla_page_load 05:13:47 INFO - PROCESS | 6733 | _x_x_mozilla_page_load_details 05:13:47 INFO - PROCESS | 6733 | |i|pagename|runs| 05:13:47 INFO - PROCESS | 6733 | |0;ai-astar;113;113;114;113;111;113;112;113;113;113 05:13:47 INFO - PROCESS | 6733 | |1;audio-beat-detection;149;117;141;183;129;114;117;129;182;140 05:13:47 INFO - PROCESS | 6733 | |2;audio-dft;185;181;184;188;204;185;184;185;181;183 05:13:47 INFO - PROCESS | 6733 | |3;audio-fft;81;58;58;88;61;59;58;57;83;60 05:13:47 INFO - PROCESS | 6733 | |4;audio-oscillator;93;87;126;90;68;68;90;67;69;91 05:13:47 INFO - PROCESS | 6733 | |5;imaging-gaussian-blur;88;91;88;85;88;84;88;86;88;84 05:13:47 INFO - PROCESS | 6733 | |6;imaging-darkroom;93;93;94;94;94;94;93;93;93;94 05:13:47 INFO - PROCESS | 6733 | |7;imaging-desaturate;80;81;83;80;83;81;83;84;82;81 05:13:47 INFO - PROCESS | 6733 | |8;json-parse-financial;55;53;53;53;54;55;55;55;55;55 05:13:47 INFO - PROCESS | 6733 | |9;json-stringify-tinderbox;64;71;71;74;73;70;80;104;103;88 05:13:47 INFO - PROCESS | 6733 | |10;stanford-crypto-aes;92;88;87;86;90;85;86;98;108;88 05:13:47 INFO - PROCESS | 6733 | |11;stanford-crypto-ccm;153;148;150;156;161;161;151;145;187;149 05:13:47 INFO - PROCESS | 6733 | |12;stanford-crypto-pbkdf2;176;180;176;173;174;178;174;174;177;176 05:13:47 INFO - PROCESS | 6733 | |13;stanford-crypto-sha256-iterative;67;68;67;68;68;66;65;69;66;67 05:13:47 INFO - PROCESS | 6733 | __end_tp_report 05:13:47 INFO - PROCESS | 6733 | __start_cc_report 05:13:47 INFO - PROCESS | 6733 | _x_x_mozilla_cycle_collect,2002 05:13:47 INFO - PROCESS | 6733 | __end_cc_report 05:13:47 INFO - PROCESS | 6733 | __startTimestamp1478697227404__endTimestamp 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | ------- Summary: start ------- 05:13:47 INFO - PROCESS | 6733 | Number of tests: 14 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:0.79 (0.7%) stddev-sans-first:0.83 05:13:47 INFO - PROCESS | 6733 | Values: 113.0 113.0 114.0 113.0 111.0 113.0 112.0 113.0 113.0 113.0 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:140.50 stddev:25.10 (17.9%) stddev-sans-first:26.42 05:13:47 INFO - PROCESS | 6733 | Values: 149.0 117.0 141.0 183.0 129.0 114.0 117.0 129.0 182.0 140.0 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | [#2] audio-dft Cycles:10 Average:186.00 Median:185.00 stddev:6.65 (3.6%) stddev-sans-first:7.04 05:13:47 INFO - PROCESS | 6733 | Values: 185.0 181.0 184.0 188.0 204.0 185.0 184.0 185.0 181.0 183.0 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | [#3] audio-fft Cycles:10 Average:66.30 Median:60.50 stddev:12.38 (20.5%) stddev-sans-first:11.94 05:13:47 INFO - PROCESS | 6733 | Values: 81.0 58.0 58.0 88.0 61.0 59.0 58.0 57.0 83.0 60.0 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | [#4] audio-oscillator Cycles:10 Average:84.90 Median:88.50 stddev:18.24 (20.6%) stddev-sans-first:19.10 05:13:47 INFO - PROCESS | 6733 | Values: 93.0 87.0 126.0 90.0 68.0 68.0 90.0 67.0 69.0 91.0 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:88.00 stddev:2.21 (2.5%) stddev-sans-first:2.32 05:13:47 INFO - PROCESS | 6733 | Values: 88.0 91.0 88.0 85.0 88.0 84.0 88.0 86.0 88.0 84.0 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 05:13:47 INFO - PROCESS | 6733 | Values: 93.0 93.0 94.0 94.0 94.0 94.0 93.0 93.0 93.0 94.0 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | [#7] imaging-desaturate Cycles:10 Average:81.80 Median:82.50 stddev:1.40 (1.7%) stddev-sans-first:1.32 05:13:47 INFO - PROCESS | 6733 | Values: 80.0 81.0 83.0 80.0 83.0 81.0 83.0 84.0 82.0 81.0 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:55.00 stddev:0.95 (1.7%) stddev-sans-first:0.97 05:13:47 INFO - PROCESS | 6733 | Values: 55.0 53.0 53.0 53.0 54.0 55.0 55.0 55.0 55.0 55.0 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | [#9] json-stringify-tinderbox Cycles:10 Average:79.80 Median:72.00 stddev:14.03 (19.5%) stddev-sans-first:13.67 05:13:47 INFO - PROCESS | 6733 | Values: 64.0 71.0 71.0 74.0 73.0 70.0 80.0 104.0 103.0 88.0 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:88.00 stddev:7.15 (8.1%) stddev-sans-first:7.57 05:13:47 INFO - PROCESS | 6733 | Values: 92.0 88.0 87.0 86.0 90.0 85.0 86.0 98.0 108.0 88.0 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | [#11] stanford-crypto-ccm Cycles:10 Average:156.10 Median:154.50 stddev:12.09 (7.8%) stddev-sans-first:12.77 05:13:47 INFO - PROCESS | 6733 | Values: 153.0 148.0 150.0 156.0 161.0 161.0 151.0 145.0 187.0 149.0 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:176.00 stddev:2.15 (1.2%) stddev-sans-first:2.28 05:13:47 INFO - PROCESS | 6733 | Values: 176.0 180.0 176.0 173.0 174.0 178.0 174.0 174.0 177.0 176.0 05:13:47 INFO - PROCESS | 6733 | 05:13:47 INFO - PROCESS | 6733 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:1.20 (1.8%) stddev-sans-first:1.27 05:13:47 INFO - PROCESS | 6733 | Values: 67.0 68.0 67.0 68.0 68.0 66.0 65.0 69.0 66.0 67.0 05:13:47 INFO - PROCESS | 6733 | -------- Summary: end -------- 05:13:47 INFO - PROCESS | 6733 | 05:13:48 INFO - TEST-INFO | 6733: exit 0 05:13:48 INFO - TEST-OK | kraken | took 60262ms 05:13:48 INFO - SUITE-END | took 1179s 05:13:48 INFO - Completed test suite (00:19:40) 05:13:48 INFO - javascript benchmark 05:13:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3284.9835750821258, 3299.8020118792874, 3317.071220058384, 3335.6164041135276, 3367.7979321240714, 5537.501624853762, 5631.690257035862, 5632.986062508747, 5743.052396354602, 5765.45228203321, 5603.551715862732, 5664.376918538967, 5666.546676265899, 5668.433156684329, 5671.432856714337, 5121.334226550553, 5147.202183661539, 5159.587233021368, 5183.911378610496, 5192.143296356116, 5189.273501709764, 5208.947910520884, 5248.0815857225025, 5278.6568873023325, 5297.0, 4355.390245365636, 5264.026155161288, 5270.104082305998, 5328.866778330554, 5345.465453454663, 627.6017153530772, 5258.447862974409, 5273.630845840793, 5280.551153151987, 5313.0, 3122.7353103168452, 5067.631739430333, 5113.923291150636, 5154.5103215194495, 5174.534291913746, 4947.356843610308, 5608.775648974036, 5618.381977982419, 5635.0, 5683.374828768832, 6225.595336303126, 6238.438540531329, 6257.217847769029, 6262.091996660504, 6275.152854364648, 4461.732296062247, 6134.110553969694, 6154.107654390134, 6177.04255840341, 6181.598196117238, 5693.060644993571, 5723.713814309268, 5749.0801471764735, 5791.391903850119, 5792.189093526909, 6281.497480201573, 6297.842553936188, 6299.464545513591, 6303.212098487689, 6311.084892690534, 5932.58471906971, 5968.820935371946, 5979.521638268928, 6035.698215089228, 6094.116353128771, 6113.354685976905, 6171.475424588871, 6188.566800323934, 6226.221722284715, 6235.158253635792, 6174.783882564088, 6194.163787888668, 6196.0, 6206.224221972254, 6214.658193799384, 6089.817305480843, 6121.46297486165, 6152.477039451696, 6199.907001394983, 6228.564000520011, 4906.2640603908985, 4921.434035085939, 4940.604751619861, 4943.184374578226, 4974.676646017997, 4388.934165987513, 4396.560343965578, 4396.670249731283, 4399.758013309299, 4441.333799929972, 5665.575081868877, 5692.544596432275, 5735.11105778605, 5737.139429085587, 5737.311522617312, 5960.85097872548, 6107.735039974563, 6112.807455516499, 6145.569810113249, 6205.410486003822, 5724.141378793214, 5739.16782066598, 5741.081426971665, 5749.54003679713, 5776.220210271644, 5385.138377859524, 5394.838154855361, 5398.082326004589, 5405.837824865261, 5417.214503896913, 6189.226346706661, 6212.347703491068, 6269.49844012487, 6271.62370257786, 6287.7799277026, 6134.742747059018, 6176.969115154575, 6177.505799535937, 6190.628562286277, 6273.309935906956, 6248.718807653576, 6251.874962500816, 6270.2475702915945, 6277.403646653561, 6319.715612797343, 6025.758969641341, 6130.356312587114, 6137.079438084323, 6158.291796443287, 6161.969190154021, 6014.939850601613, 6136.048912418582, 6144.310314663503, 6197.411245931629, 6239.282482514569, 6089.1779609753885, 6108.908366374597, 6111.205543279345, 6137.018077107641, 6137.7851775187655, 5941.554383421174, 6063.757449701962, 6126.632402055891, 6136.447719705248, 6144.262688477412, 5747.885042299215, 5786.566007549366, 5834.766609335748, 5861.443162899518, 5887.676177810262, 5991.310999234969, 6056.0, 6065.696715164136, 6091.177691011657, 6109.419605137505, 5757.683327417032, 5799.623024503394, 5800.854978625568, 5821.825345239649, 5840.707964601838], "value": 5606.582976309649, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.54303097510893, 500.3045766384725, 507.5357593343204, 518.1088527732302, 547.5646860745709, 25261.0, 38267.043323916914, 39064.41403378948, 39088.0, 41599.58400415995, 40703.59296407035, 40928.59071409285, 41073.0, 41114.79442602783, 41190.794046029805, 8367.577511822974, 9225.354225204239, 9419.85870211944, 9431.528423578828, 9432.622695092188, 8363.121872203423, 8974.730758077236, 11241.0, 11242.0, 11438.771224575503, 11178.155604325277, 11232.887671123266, 11240.437978101103, 11245.100391968623, 11249.775004499905, 8286.502809831369, 8548.145185481464, 11176.105911527058, 11254.943725281362, 11286.209965302432, 8419.494830310201, 8492.872606910902, 8502.872456913152, 10655.041046305869, 10996.010359067643, 39112.608873911464, 39120.0, 39274.60725392738, 39346.016349591206, 39354.803225983684, 3066.23344163959, 3109.54911537828, 3342.632310445849, 3367.44437167867, 3624.27514497099, 27562.5865612016, 29994.550081748574, 30142.54786178209, 31277.687223127705, 31284.53073203904, 3094.814311141316, 3113.128324069264, 3278.459054256045, 3317.883874064396, 3324.202191474029, 475580.0, 476212.0, 478556.21443785814, 479493.6025319886, 482274.0, 53888.0, 56290.718546407006, 56315.0, 56425.43574564284, 56431.43568564344, 58290.542736431926, 58955.410445894995, 59009.819803603685, 59014.11478827821, 59147.704261478415, 15238.857085718615, 15620.76568851468, 15625.453109141354, 15650.608734781721, 15680.372785088697, 11000.119990400748, 11212.607558735404, 11216.719582010515, 12138.968187703967, 12264.693382665504, 14676.412943482355, 14744.55766327012, 14972.850271497146, 15061.322240499203, 15110.320035598425, 5990.400959903975, 6910.170779506391, 6954.79135625932, 6988.580685158805, 7006.684699188549, 862.2067697717989, 949.9952500237592, 950.6577632052456, 976.2482888176065, 988.2637435110745, 25889.093881714427, 27501.174964751088, 29102.417951640848, 29379.265518362212, 29731.702682972893, 1018.1752780248023, 1042.588177669816, 1064.223117124488, 1086.2613422872523, 1107.4684151607273, 14975.400983960737, 14977.326020329112, 14988.550343489713, 15358.616034599225, 15486.148261845707, 619373.0, 690075.0, 690983.6270466192, 691085.0, 889297.0, 54503.0, 55153.72423137858, 55264.17103743447, 55332.72333638306, 55365.44634553683, 1211.6607349941853, 1241.5778635264053, 1320.2804471563022, 1342.0069148829991, 1364.4269406849148, 23695.490541464253, 24310.75689243085, 30408.695913041473, 30568.93008744683, 32131.878458285566, 50475.74762126166, 50914.49085509246, 50978.0, 51062.74468627633, 51194.74402627963, 565192.0, 570508.0, 571036.0, 571584.0, 571848.0, 22282.77717222807, 22884.199053033062, 23277.254205934285, 24485.877570612032, 24962.750372496044, 36822.63177368192, 37266.8136659315, 37304.253914921006, 38817.0, 38885.8055709731, 33577.83211083929, 35588.82205589059, 35689.64310356863, 36295.0, 37311.253774924895, 35071.47392789161, 35939.46090808692, 36008.0, 36591.26817463689, 36878.63121368752, 35863.82068089747, 36085.45871811977, 36159.0, 36864.44703329399, 36989.445158323186, 29319.560206596492, 33027.75210451435, 33502.162445939044, 33612.0, 33921.830390847885, 22700.0, 24402.51194976126, 24801.62797558002, 24872.502549949262, 25123.497530049663], "value": 19231.027088280345, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.55036855036855, 372.9925401491968, 382.1802234207624, 394.044442227598, 404.38331544394794, 15209.239538023083, 15313.693726125472, 18277.26890924363, 20687.482812929666, 20818.063187156575, 17939.282428702867, 19420.320288789895, 20345.49136271594, 20362.389128326136, 20365.18539258428, 5672.574556908227, 5955.285365756094, 5961.88076238475, 5961.940380596204, 5973.372795856438, 4147.5068975168915, 4389.297712366006, 5800.941990580082, 5910.113482977545, 6162.815115546518, 6357.936420635804, 6534.705938232791, 6535.901961470582, 6536.934630653704, 6543.1821022372205, 6202.85247229264, 6411.166548348731, 6482.513811464112, 6492.545521813475, 6531.4448271896945, 5433.212184233304, 5493.615446918718, 5624.21261023457, 5624.268845050158, 5634.971825140889, 24481.387965300833, 24485.0, 24700.5059898801, 24807.0, 24837.882295296575, 1865.0301843041677, 2038.0828627117855, 2106.3996760923064, 2310.237621584873, 2570.7043689975703, 9232.8615070774, 9582.329236953416, 9698.757531061709, 9799.412035277906, 9993.600255989752, 1642.3266460751036, 1708.8034875989297, 1709.2479309104078, 1718.4243278501717, 1775.3874913154941, 113845.0, 119596.0, 122665.38667306695, 122684.0, 123167.15249271267, 8504.702335418231, 9991.400515969066, 9999.900010998785, 10165.898341016495, 10699.090577300862, 12765.93617031909, 13606.251656159006, 14063.0, 14088.0, 14158.292085395895, 6355.872882542416, 6533.314002029813, 7162.493127405471, 8051.919480805117, 8216.178382161735, 8012.0786109596975, 8060.153683863227, 8091.716789912325, 8246.793830154294, 8256.876146857801, 4333.068390296102, 4355.73865568067, 4450.017210011284, 4455.844045458393, 4504.819807207675, 1757.534253422844, 1786.0948063772526, 1870.4856164554856, 1921.4619906426224, 1945.6497830390665, 757.9469437139347, 791.2404092071561, 858.9785255368665, 898.2589363774912, 908.8773015642804, 19176.616467670567, 20481.48796280105, 20616.793832061787, 20686.275980340917, 20780.896095519427, 752.9887051694229, 842.389267780854, 905.1265528764774, 909.6225066597289, 940.1022023967095, 10491.895081049091, 10526.889606914163, 10980.780384392267, 11035.62054743157, 11049.447527623586, 542531.0, 574157.0, 578226.0, 591615.0, 594580.0, 130742.34628826795, 132612.0, 134223.65776342113, 134554.3272283652, 134791.0, 919.7562645898843, 933.6778811310087, 943.4527973775331, 955.8948515663271, 979.7795496013482, 14064.437422503395, 18499.0, 19225.903870480557, 19268.614627707648, 19339.90330048341, 52356.47643523516, 54690.45309546853, 56071.158932615224, 56276.71861640666, 56465.0, 75633.0, 75893.0, 76310.09224769425, 76416.0, 79320.0, 7832.647530861239, 7908.327792137503, 7947.284744373034, 8044.3564514839845, 8320.875186872323, 11702.085243809825, 12368.19606725596, 12480.068778334795, 12623.368831558202, 12732.172408793398, 10354.948225258826, 10636.255462117577, 10972.067374272958, 10985.01134897831, 11034.668959931216, 9578.80264966879, 10076.345037572535, 10401.895981040396, 10556.788864222826, 10997.890021099687, 8947.507887066275, 10092.788865336206, 10817.783644326912, 10874.0, 10890.23768336209, 7895.171007044178, 7964.2433968772875, 7977.521348719096, 8002.59987000636, 8042.622098718435, 9877.654282100091, 10668.46657667098, 10852.40311782828, 10859.34843909368, 10979.28634638746], "value": 9252.551114556345, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.2822645130336, 1516.3328135620327, 1666.5916850371661, 1819.490542648059, 1828.5154434074964, 7919.8416031679335, 8447.15528447155, 8450.95774521128, 8459.49243045417, 8480.915190848098, 7370.8894366584545, 7628.199039100898, 7631.0, 7990.560519171443, 8211.91788082119, 1903.7620297462818, 2322.628379459287, 2322.674825524428, 2326.081197926818, 2335.8481698689616, 2300.5053913408615, 2321.129576408847, 2330.7902288794007, 2353.8587684738886, 2355.1168311883043, 2298.333483289844, 2299.011425087212, 2328.953420931589, 2330.2310237621627, 2330.3242059802637, 2289.5993201189726, 2296.59809533331, 2316.7527498326763, 2319.3273950554235, 2323.802476789475, 2142.6143294207036, 2334.136369543263, 2341.7189937207645, 2344.788968992799, 2349.7415284318713, 8311.127331630152, 8397.034341050736, 8444.451110677848, 8484.066752657203, 8491.23578877904, 5036.471170527115, 5220.086484865134, 5221.138512145491, 5230.372355317349, 5244.0036393085575, 3043.3761078978755, 3219.951700724491, 3388.203772113551, 3578.4095624221973, 3587.982060089683, 1044.2742294105599, 1049.3861091261622, 1074.006543946846, 1100.0759362135764, 1104.729341311384, 10451.790964180826, 10503.0, 10814.134869210444, 10906.563737450493, 11118.777624447466, 4258.5954334338185, 4361.847335343247, 4443.311286750559, 4489.2592722201125, 4491.708038977521, 8651.567421628893, 8669.0, 8681.826363472821, 8686.56567171639, 8724.214820666171, 4998.275250088789, 5095.617828662865, 5099.719515426613, 5109.489051094935, 5125.615578831603, 4254.851080212177, 4391.560843915583, 4696.600788932979, 4782.187028205213, 4794.041191761662, 3546.9645303546636, 3553.662402071799, 3608.657177568127, 3639.4176931690804, 3646.0, 1066.6160182334354, 1121.0023079459288, 1122.5944404846718, 1141.7031571791395, 1143.4854315558032, 792.3740245206222, 838.0278876503227, 882.6866462405882, 891.2023738753779, 925.5603588295505, 5299.947000529945, 5379.112446446371, 5628.859278517988, 5738.512226460798, 5763.049096898964, 926.5228407370209, 945.2485274207022, 966.1063516247583, 1003.9146672532917, 1039.8336266197373, 3722.7207959403154, 3728.6644202021407, 3739.8317075731657, 3854.6338097880657, 3864.942025869614, 8844.0, 10310.32982856112, 10314.27800054004, 10341.689749307532, 10349.637762678421, 10189.082982531607, 10275.0, 10278.331908426078, 10278.640247591298, 10302.536385862653, 7381.741639042715, 7804.141544430108, 7947.5231486111015, 7968.880466793003, 7975.082865470429, 5175.120229561059, 5591.720413979366, 5697.57268204878, 5730.5702072345575, 5828.533717302521, 8964.058773828654, 9124.589393477178, 9366.250699944125, 9494.905050949403, 9501.572429240563, 9244.768880778034, 9370.062993700576, 9571.138597526256, 9676.612935482783, 9686.32195746291, 7113.430925525842, 7339.412846972123, 7349.1915889253205, 7634.847303054019, 7850.293473587404, 7467.626618669154, 7678.078630564145, 7956.9602151988865, 7983.2415920487465, 8103.959480202561, 5947.286325640778, 7193.928060719326, 7235.312645298688, 7248.347648711641, 7384.37232835215, 7025.578465292099, 7034.964825175842, 7047.048648432396, 7056.6118863463, 7161.57030578167, 6686.197656281277, 6707.463402927851, 6755.256921738703, 6776.254611992578, 6790.8641827164165, 4775.403074615673, 4851.544536639065, 4858.684185527929, 4866.635002374906, 4957.3059771632725, 4700.918788678691, 4826.734529600905, 4886.242632391985, 4920.532549407801, 4935.0], "value": 4394.127240301837, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.6018273503419, 298.7027907232304, 301.55972280470513, 304.74705994024964, 317.30668489350757, 4816.638752093599, 5299.947000529994, 5326.813561525348, 5376.865578360543, 5404.675719456835, 4395.494518130416, 4518.751468669222, 4805.423349198092, 4835.823879254332, 5099.184130539114, 3491.703205227546, 3843.7501562398493, 3947.4868267125307, 4033.495813023372, 4052.0, 3326.534285200074, 4018.3972404139467, 4030.5364883038533, 4037.9394309085387, 4043.5552089270304, 3843.61629813267, 3910.315694753421, 4019.1157945252, 4036.3541833306676, 4045.656119229869, 3671.541057367829, 4001.3197756381487, 4019.879403617896, 4036.777977211252, 4043.777592232426, 3507.8421471033867, 3507.8947631571095, 3513.2622149348667, 3515.33208690348, 3526.400511912981, 4657.534246575349, 4734.360861283752, 4812.109759694433, 5196.870078248074, 5218.347706536683, 4327.870163895088, 4560.338750881104, 4603.171429142753, 4617.284320930261, 4642.303654451825, 4692.826793301675, 5079.958608485252, 5113.642045056848, 5388.595855310868, 5530.893821235729, 3326.96673033269, 3792.1846802937503, 3837.597052309523, 3841.5006049213425, 3854.1906199698096, 4864.464908860023, 5568.721563921828, 5798.608333999852, 5845.649261044351, 5852.795152169696, 3887.922241555153, 3889.4554762333296, 3890.9416358754643, 3894.6105389460827, 3900.278448487039, 3618.805794087945, 3687.6399104573093, 3912.628300311466, 3944.15200731844, 3950.1309711863346, 3793.525809273841, 4224.028473451062, 4317.136572685476, 4533.750643714561, 4591.816327346869, 5593.356763972195, 5614.9438505615235, 5616.522595579421, 5620.662760234399, 5635.830925072254, 1094.5074716377662, 1206.818977153434, 1272.287518989369, 1278.6931136527353, 1280.8408390406696, 753.8428512233804, 826.2811354121952, 836.4024712798525, 846.2552953400967, 846.7502086884384, 4485.641148708096, 5290.603204759658, 5310.787568497217, 5321.5476684482255, 5366.91949620756, 5196.870078248074, 5345.224942383333, 5533.612647114664, 5759.625624334405, 5811.535077193815, 4530.478994915561, 4661.370714953506, 4947.332110165082, 5128.1026020371955, 5320.388155362183, 2374.0414158319454, 2557.889740963675, 2780.860956952144, 2861.3561948561405, 2878.4387044526115, 4815.542523460265, 5301.595077304517, 5340.513968266675, 5647.261078376984, 5699.914501282484, 4957.553820156131, 5534.75093620788, 5586.636868603528, 5652.943470565242, 5685.630434021776, 4655.837045703384, 5228.4510126436935, 5243.030039442715, 5592.972035139798, 5620.943790562205, 4607.654425918003, 4989.106318335284, 5026.447090819934, 5367.463253674601, 5429.972850135882, 4509.481409637933, 5265.289185959847, 5419.295491586068, 5713.05734553794, 5781.0, 5261.73691315425, 6015.338312785678, 6031.004884194059, 6032.788852390146, 6034.758609655564, 3327.885158471939, 3689.7786132832116, 3812.8076356094803, 3845.942310865283, 3923.843046278117, 5024.874378140429, 5299.364076310867, 5518.44815518461, 5568.888622227614, 5829.970850145722, 5660.462256085665, 5661.943380566142, 5666.46168613981, 5676.886462270649, 5698.943010570007, 5401.207680168863, 5621.325440947113, 5651.982643124277, 5701.7149142541875, 5726.856828579319, 5289.761960711854, 5495.0, 5508.476694713996, 5582.553395728413, 5601.831945041656, 4741.846671963244, 4862.346802087307, 4909.723471897261, 5371.301730774974, 5592.86017849557, 4848.3454733610515, 5041.924371134362, 5497.45025497447, 5539.7784088636, 5555.916661250165], "value": 4002.358006214633, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.8235441139715, 722.5484072454713, 761.2805898425989, 793.9285464308211, 808.5997431271521, 13277.601671949846, 13528.120672156327, 13762.724745505084, 13806.792898106523, 13855.515056972996, 12406.751864962695, 12464.875351246485, 12472.563460278892, 12510.749785004316, 12515.93614542765, 678.375894177358, 701.4003027411566, 820.2781552234026, 830.4685001598981, 849.2823564088335, 838.6854929401475, 845.2012847858776, 852.0669866496177, 857.2327766648846, 858.7767180533075, 899.6311512279966, 905.2124651553158, 906.7869050773063, 908.1100521488945, 910.5629297936994, 715.2597062040815, 901.779064129286, 902.1700035966926, 902.6118768929391, 902.9187373136449, 552.9281193444866, 647.1037612906111, 673.4040374268808, 676.6853413162903, 682.8634273145391, 11300.604478843201, 11652.708682282926, 12341.444634991358, 12386.81419778704, 12388.56640017595, 11512.769744605144, 11591.942040289745, 11619.709507262301, 11787.587434439753, 12084.758304833855, 10135.949320253425, 11381.487833047533, 11411.48648310828, 11471.139664525193, 11551.133664975076, 3755.6244375562496, 3786.6024067472795, 3848.0956975110826, 3998.140399814054, 4000.439938408625, 79391.603041985, 81480.0, 82546.17453825445, 84025.15974840235, 84156.15843841544, 8688.739337819876, 8762.255208307237, 8897.243734282529, 9060.184583387527, 9246.306527010502, 17530.47408577745, 17791.19939602721, 17959.191836367394, 17992.37026704059, 18004.459866204033, 6684.362331228783, 6731.0, 6747.628880411527, 6754.310944927294, 6871.793846184622, 3714.220013797079, 3748.868789592406, 3768.5477742671055, 3787.110029143149, 3796.2976849282973, 3070.6008218931397, 3128.6089238845143, 3184.7770656054317, 3188.505781603855, 3195.2171717929255, 584.8830233953227, 600.4205941266698, 608.4767100293744, 617.5800455690083, 619.5508256514081, 587.8089620873147, 603.1616053685381, 619.4610688700859, 620.906863970408, 628.3590737447778, 6806.591604503746, 7412.481126321213, 8004.0, 8042.879356809653, 8157.632906519221, 515.2760371677799, 553.7867920850421, 561.943805619443, 564.7232855900661, 624.7313655128247, 3166.9683303167135, 3876.8449262029767, 3944.7633142010995, 4012.378081397388, 4027.0, 90332.0, 101526.96946061037, 101655.96688066197, 103689.48155259175, 105209.0, 98552.0, 101423.0, 101458.49270753599, 103373.0, 104170.47914760378, 86477.0, 88260.0, 89521.55239223762, 90639.09360906307, 90971.54514227387, 7599.0, 7611.809704757426, 7738.92261077382, 7792.1428642848105, 7971.1630278819885, 38822.80588597039, 48002.759986199846, 48616.75691621519, 51079.956801727516, 52267.95464090773, 65245.347546523924, 69891.65054174696, 75438.62280688781, 76536.70389888089, 77445.61277193578, 9974.55114519863, 10013.799724005625, 10090.394576325443, 10106.242031847494, 10374.42940638242, 11299.774004520028, 11630.592929247436, 11641.708957275798, 11711.941440292743, 12027.037836973197, 10569.0, 10772.784544309226, 10776.407297598395, 10874.565017399216, 11222.18671353805, 10660.573577056832, 11638.185327027028, 11931.284122952304, 12151.149419540547, 12176.39118044112, 11822.11334149925, 11881.168318217642, 11928.76142477163, 12402.441890114786, 12492.937535312265, 9996.800063998533, 10044.748881278027, 10111.59553617847, 10428.53071611764, 10752.0, 7964.561949092856, 9390.859137112811, 9735.41587504752, 9998.05018523239, 10016.098551130433], "value": 6756.201867725682, "unit": "score"}], "name": "dromaeo_css", "value": 7008.9027944210675, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 111.0, 113.0, 112.0, 113.0, 113.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 141.0, 183.0, 129.0, 114.0, 117.0, 129.0, 182.0, 140.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 181.0, 184.0, 188.0, 204.0, 185.0, 184.0, 185.0, 181.0, 183.0], "value": 186.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 58.0, 88.0, 61.0, 59.0, 58.0, 57.0, 83.0, 60.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 87.0, 126.0, 90.0, 68.0, 68.0, 90.0, 67.0, 69.0, 91.0], "value": 84.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 91.0, 88.0, 85.0, 88.0, 84.0, 88.0, 86.0, 88.0, 84.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 93.0, 94.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 83.0, 80.0, 83.0, 81.0, 83.0, 84.0, 82.0, 81.0], "value": 81.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 53.0, 54.0, 55.0, 55.0, 55.0, 55.0, 55.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 71.0, 74.0, 73.0, 70.0, 80.0, 104.0, 103.0, 88.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 87.0, 86.0, 90.0, 85.0, 86.0, 98.0, 108.0, 88.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 148.0, 150.0, 156.0, 161.0, 161.0, 151.0, 145.0, 187.0, 149.0], "value": 156.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 180.0, 176.0, 173.0, 174.0, 178.0, 174.0, 174.0, 177.0, 176.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 68.0, 68.0, 66.0, 65.0, 69.0, 66.0, 67.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1476.2999999999997, "alertThreshold": 2.0}]} 05:13:48 INFO - Return code: 0 05:13:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:13:48 INFO - # TBPL SUCCESS # 05:13:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:13:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:13:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:13:48 INFO - 2016-11-09 05:13:48.738 screenresolution[6737:128348] starting screenresolution argv=screenresolution get 05:13:48 INFO - 2016-11-09 05:13:48.741 screenresolution[6737:128348] Display 0: 1600x1200x32@60 05:13:48 INFO - 2016-11-09 05:13:48.747 screenresolution[6738:128351] starting screenresolution argv=screenresolution list 05:13:48 INFO - Available Modes on Display 0 05:13:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:13:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:13:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:13:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:13:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:13:48 INFO - Intel Iris: 05:13:48 INFO - Chipset Model: Intel Iris 05:13:48 INFO - Type: GPU 05:13:48 INFO - Bus: Built-In 05:13:48 INFO - VRAM (Dynamic, Max): 1536 MB 05:13:48 INFO - Vendor: Intel (0x8086) 05:13:48 INFO - Device ID: 0x0a2e 05:13:48 INFO - Revision ID: 0x0009 05:13:48 INFO - Displays: 05:13:48 INFO - Display: 05:13:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:13:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:13:48 INFO - Display Serial Number: 1600x1200 60 05:13:48 INFO - Main Display: Yes 05:13:48 INFO - Mirror: Off 05:13:48 INFO - Online: Yes 05:13:48 INFO - Rotation: Supported 05:13:48 INFO - Return code: 0 05:13:48 INFO - Running post-action listener: _resource_record_post_action 05:13:48 INFO - [mozharness: 2016-11-09 13:13:48.988420Z] Finished run-tests step (success) 05:13:48 INFO - Running post-run listener: _resource_record_post_run 05:13:49 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 524800; Write bytes: 704546304; Read time: 5; Write time: 1995 05:13:49 INFO - TinderboxPrint: CPU usage
53.7% 05:13:49 INFO - TinderboxPrint: I/O read bytes / time
524,800 / 5 05:13:49 INFO - TinderboxPrint: I/O write bytes / time
704,546,304 / 1,995 05:13:49 INFO - TinderboxPrint: CPU idle
2,250.4 (46.3%) 05:13:49 INFO - TinderboxPrint: CPU system
980.9 (20.2%) 05:13:49 INFO - TinderboxPrint: CPU user
1,630.3 (33.5%) 05:13:49 INFO - TinderboxPrint: Swap in / out
403,361,792 / 0 05:13:49 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402991616; Write bytes: 397129216; Read time: 29933; Write time: 826 05:13:49 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 483328; Write bytes: 297336832; Read time: 3; Write time: 1132 05:13:49 INFO - Running post-run listener: _upload_blobber_files 05:13:49 INFO - Blob upload gear active. 05:13:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:13: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 05:13: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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:13: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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:13:49 INFO - (blobuploader) - INFO - Open directory for files ... 05:13:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:13:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:13:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:50 INFO - (blobuploader) - INFO - Done attempting. 05:13:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:13:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:13:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:51 INFO - (blobuploader) - INFO - Done attempting. 05:13:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:13:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:13:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:54 INFO - (blobuploader) - INFO - Done attempting. 05:13:54 INFO - (blobuploader) - INFO - Iteration through files over. 05:13:54 INFO - Return code: 0 05:13:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:13:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:13:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e48ab28bb20e8320d6c834ac99b64306aeb3599e2b154115e0ca5c18e3859b97e82dd0e5a2e438a694cb48130afb3cadc33b6d749c175cab957206433e1f9910", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/2fddbbeca0a80745d683bc31361199f10c03f161b76db3b9189508ddccd93a5c8d13fd5e79c590901df762fdd6fa02953e63a189a9fa16210a4680b7c6556c44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/fa23d8296764dcdd29affa34fbb906c7bdf69550f2f61a3d91be532e0ffc05fee8ce3c58d9911414f609ccbe50243b6afd3c6541e25095e25137ffa2f3e0a14c"} 05:13:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:13:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:13:54 INFO - Contents: 05:13:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e48ab28bb20e8320d6c834ac99b64306aeb3599e2b154115e0ca5c18e3859b97e82dd0e5a2e438a694cb48130afb3cadc33b6d749c175cab957206433e1f9910", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/2fddbbeca0a80745d683bc31361199f10c03f161b76db3b9189508ddccd93a5c8d13fd5e79c590901df762fdd6fa02953e63a189a9fa16210a4680b7c6556c44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/fa23d8296764dcdd29affa34fbb906c7bdf69550f2f61a3d91be532e0ffc05fee8ce3c58d9911414f609ccbe50243b6afd3c6541e25095e25137ffa2f3e0a14c"} 05:13:54 INFO - Running post-run listener: copy_logs_to_upload_dir 05:13:54 INFO - Copying logs to upload dir... 05:13:54 INFO - mkdir: /builds/slave/test/build/upload/logs 05:13:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.025513 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-09 05:13:54.467544) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-09 05:13:54.471353) ========= 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.ZOEZfFQIsW/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.LdHtJYXIws/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e48ab28bb20e8320d6c834ac99b64306aeb3599e2b154115e0ca5c18e3859b97e82dd0e5a2e438a694cb48130afb3cadc33b6d749c175cab957206433e1f9910", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/2fddbbeca0a80745d683bc31361199f10c03f161b76db3b9189508ddccd93a5c8d13fd5e79c590901df762fdd6fa02953e63a189a9fa16210a4680b7c6556c44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/fa23d8296764dcdd29affa34fbb906c7bdf69550f2f61a3d91be532e0ffc05fee8ce3c58d9911414f609ccbe50243b6afd3c6541e25095e25137ffa2f3e0a14c"} build_url:https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011528 build_url: 'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e48ab28bb20e8320d6c834ac99b64306aeb3599e2b154115e0ca5c18e3859b97e82dd0e5a2e438a694cb48130afb3cadc33b6d749c175cab957206433e1f9910", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/2fddbbeca0a80745d683bc31361199f10c03f161b76db3b9189508ddccd93a5c8d13fd5e79c590901df762fdd6fa02953e63a189a9fa16210a4680b7c6556c44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/fa23d8296764dcdd29affa34fbb906c7bdf69550f2f61a3d91be532e0ffc05fee8ce3c58d9911414f609ccbe50243b6afd3c6541e25095e25137ffa2f3e0a14c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-09 05:13:54.504865) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 05:13:54.505191) ========= 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.ZOEZfFQIsW/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.LdHtJYXIws/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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.005411 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 05:13:54.553697) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-09 05:13:54.557052) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-09 05:13:54.557376) ========= ========= Total master_lag: 0.17 =========