builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0152 starttime: 1475857009.41 results: success (0) buildid: 20161006150000 builduid: 41e23adb98db465393728f1328598cef revision: d8e1f5cf0a70a53e8a5532809096a0a5bf729196 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-07 09:16:49.408652) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-07 09:16:49.409085) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-07 09:16:49.409386) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-07 09:16:49.433926) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 09:16:49.434223) ========= 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.kgMleUVsEB/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.TA6MAjnkqL/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-07 09:16:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.70M=0.001s 2016-10-07 09:16:49 (9.70 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.090412 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 09:16:49.546881) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 09:16:49.547197) ========= 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.kgMleUVsEB/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.TA6MAjnkqL/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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.027901 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 09:16:49.595747) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-07 09:16:49.596115) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d8e1f5cf0a70a53e8a5532809096a0a5bf729196 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev d8e1f5cf0a70a53e8a5532809096a0a5bf729196 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kgMleUVsEB/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.TA6MAjnkqL/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-07 09:16:49,668 truncating revision to first 12 chars 2016-10-07 09:16:49,668 Setting DEBUG logging. 2016-10-07 09:16:49,668 attempt 1/10 2016-10-07 09:16:49,668 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d8e1f5cf0a70?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-07 09:16:50,801 unpacking tar archive at: autoland-d8e1f5cf0a70/testing/mozharness/ program finished with exit code 0 elapsedTime=1.393935 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-07 09:16:51.011602) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-07 09:16:51.011938) ========= script_repo_revision: d8e1f5cf0a70a53e8a5532809096a0a5bf729196 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-07 09:16:51.012316) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-07 09:16:51.012582) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-07 09:16:51.028281) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-07 09:16:51.028556) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kgMleUVsEB/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.TA6MAjnkqL/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 09:16:51 INFO - MultiFileLogger online at 20161007 09:16:51 in /builds/slave/test 09:16:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 09:16:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:16:51 INFO - {'append_to_log': False, 09:16:51 INFO - 'base_work_dir': '/builds/slave/test', 09:16:51 INFO - 'blob_upload_branch': 'Autoland', 09:16:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:16:51 INFO - 'branch': 'Autoland', 09:16:51 INFO - 'buildbot_json_path': 'buildprops.json', 09:16:51 INFO - 'config_files': ('talos/mac_config.py',), 09:16:51 INFO - 'default_actions': ('clobber', 09:16:51 INFO - 'read-buildbot-config', 09:16:51 INFO - 'download-and-extract', 09:16:51 INFO - 'populate-webroot', 09:16:51 INFO - 'create-virtualenv', 09:16:51 INFO - 'install', 09:16:51 INFO - 'run-tests'), 09:16:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:16:51 INFO - 'download_minidump_stackwalk': True, 09:16:51 INFO - 'download_symbols': 'ondemand', 09:16:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:16:51 INFO - 'tooltool.py': '/tools/tooltool.py', 09:16:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:16:51 INFO - '/tools/misc-python/virtualenv.py')}, 09:16:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:16:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:16:51 INFO - 'installer_path': 'installer.exe', 09:16:51 INFO - 'log_level': 'info', 09:16:51 INFO - 'log_name': 'talos', 09:16:51 INFO - 'log_to_console': True, 09:16:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:16:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:16:51 INFO - 'opt_config_files': (), 09:16:51 INFO - 'pip_index': False, 09:16:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:16:51 INFO - 'cmd': ('bash', 09:16:51 INFO - '-c', 09:16:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:16:51 INFO - 'enabled': True, 09:16:51 INFO - 'halt_on_failure': False, 09:16:51 INFO - 'name': 'check_screen_resolution'},), 09:16:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:16:51 INFO - 'cmd': ('bash', 09:16:51 INFO - '-c', 09:16:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:16:51 INFO - 'enabled': True, 09:16:51 INFO - 'halt_on_failure': False, 09:16:51 INFO - 'name': 'check_screen_resolution'},), 09:16:51 INFO - 'run_cmd_checks_enabled': True, 09:16:51 INFO - 'sps_profile': False, 09:16:51 INFO - 'sps_profile_interval': 0, 09:16:51 INFO - 'suite': 'dromaeojs-e10s', 09:16:51 INFO - 'system_bits': '32', 09:16:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:16:51 INFO - 'title': 't-yosemite-r7-0152', 09:16:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:16:51 INFO - 'use_talos_json': True, 09:16:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:16:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:16:51 INFO - 'work_dir': 'build'} 09:16:51 INFO - [mozharness: 2016-10-07 16:16:51.229893Z] Running clobber step. 09:16:51 INFO - Running pre-action listener: _resource_record_pre_action 09:16:51 INFO - Running main action method: clobber 09:16:51 INFO - rmtree: /builds/slave/test/build 09:16:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:16:53 INFO - Running post-action listener: _resource_record_post_action 09:16:53 INFO - [mozharness: 2016-10-07 16:16:53.471889Z] Finished clobber step (success) 09:16:53 INFO - [mozharness: 2016-10-07 16:16:53.472043Z] Running read-buildbot-config step. 09:16:53 INFO - Running pre-action listener: _resource_record_pre_action 09:16:53 INFO - Running main action method: read_buildbot_config 09:16:53 INFO - Using buildbot properties: 09:16:53 INFO - { 09:16:53 INFO - "project": "", 09:16:53 INFO - "product": "firefox", 09:16:53 INFO - "script_repo_revision": "production", 09:16:53 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 09:16:53 INFO - "repository": "", 09:16:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 09:16:53 INFO - "buildid": "20161006150000", 09:16:53 INFO - "pgo_build": "False", 09:16:53 INFO - "basedir": "/builds/slave/test", 09:16:53 INFO - "buildnumber": 182, 09:16:53 INFO - "slavename": "t-yosemite-r7-0152", 09:16:53 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:16:53 INFO - "platform": "yosemite_r7", 09:16:53 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 09:16:53 INFO - "branch": "autoland", 09:16:53 INFO - "repo_path": "integration/autoland", 09:16:53 INFO - "revision": "d8e1f5cf0a70a53e8a5532809096a0a5bf729196", 09:16:53 INFO - "stage_platform": "macosx64", 09:16:53 INFO - "builduid": "41e23adb98db465393728f1328598cef", 09:16:53 INFO - "slavebuilddir": "test" 09:16:53 INFO - } 09:16:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 09:16:53 INFO - Running post-action listener: _resource_record_post_action 09:16:53 INFO - [mozharness: 2016-10-07 16:16:53.475431Z] Finished read-buildbot-config step (success) 09:16:53 INFO - [mozharness: 2016-10-07 16:16:53.475525Z] Running download-and-extract step. 09:16:53 INFO - Running pre-action listener: _resource_record_pre_action 09:16:53 INFO - Running main action method: download_and_extract 09:16:53 INFO - mkdir: /builds/slave/test/build/tests 09:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:53 INFO - trying https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 09:16:53 INFO - Downloading https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/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 09:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/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 09:16:55 INFO - Downloaded 1199 bytes. 09:16:55 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:16:55 INFO - Using the following test package requirements: 09:16:55 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:16:55 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:55 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 09:16:55 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:55 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 09:16:55 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:55 INFO - u'jsshell-mac.zip'], 09:16:55 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:55 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 09:16:55 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:16:55 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:55 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 09:16:55 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:55 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 09:16:55 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:55 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 09:16:55 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:16:55 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 09:16:55 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:16:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:16:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:16:55 INFO - Fetch https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:16:55 INFO - Http code: 200 09:16:55 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 09:16:55 INFO - x-amz-cf-id: I_I2pAKya-S77cwqmPJX1yQquvby7--8PYYFcdKzq5LI1wXi1Llp_g== 09:16:55 INFO - x-amz-version-id: a3y4aYbvFVmBHtIyh0vAJmlLEzrpiNI4 09:16:56 INFO - Expected file size: 16478254 09:16:56 INFO - Obtained file size: 16478254 09:17:02 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 09:17:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:17:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:17:02 INFO - Fetch https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:17:03 INFO - Http code: 200 09:17:03 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 09:17:03 INFO - x-amz-cf-id: 4mbuyhhOwb1X_Y4t9Szzo0iGm6Eq-IMB-3Oi9jLdH1VCloE286uNZg== 09:17:03 INFO - x-amz-version-id: a3y4aYbvFVmBHtIyh0vAJmlLEzrpiNI4 09:17:03 INFO - Expected file size: 16478254 09:17:03 INFO - Obtained file size: 16478254 09:17:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 09:17:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:17:10 INFO - Fetch https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 09:17:10 INFO - Http code: 200 09:17:10 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 09:17:10 INFO - x-amz-cf-id: LJcqT61AyIKxIT9-bJdbjqMPiqr9BtqOoKBjzRjfpVwuc7R9wjRzIA== 09:17:10 INFO - x-amz-version-id: Ioi0b1QeQixIXglrQfZA2nfZfUqTgLZi 09:17:11 INFO - Expected file size: 14409006 09:17:11 INFO - Obtained file size: 14409006 09:17: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')]} 09:17:11 INFO - trying https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:17:11 INFO - Downloading https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 09:17:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/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 09:17:16 INFO - Downloaded 111761123 bytes. 09:17:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:17:16 INFO - mkdir: /builds/slave/test/properties 09:17:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:17:16 INFO - Writing to file /builds/slave/test/properties/build_url 09:17:16 INFO - Contents: 09:17:16 INFO - build_url:https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:17:17 INFO - Running post-action listener: _resource_record_post_action 09:17:17 INFO - Running post-action listener: set_extra_try_arguments 09:17:17 INFO - [mozharness: 2016-10-07 16:17:17.074409Z] Finished download-and-extract step (success) 09:17:17 INFO - [mozharness: 2016-10-07 16:17:17.074540Z] Running populate-webroot step. 09:17:17 INFO - Running pre-action listener: _resource_record_pre_action 09:17:17 INFO - Running main action method: populate_webroot 09:17:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:17:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:17:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:17:17 INFO - '--tppagecycles', 09:17:17 INFO - '7'], 09:17:17 INFO - 'tests': ['tsvgm']}}, 09:17:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:17:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:17:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:17:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:17:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:17:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:17:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:17 INFO - 'talos_options': ['--disable-e10s'], 09:17:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:17 INFO - 'talos_options': ['--disable-e10s'], 09:17:17 INFO - 'tests': ['damp', 'tps']}, 09:17:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:17 INFO - 'tests': ['damp', 'tps']}, 09:17:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:17:17 INFO - 'tests': ['dromaeo_dom']}, 09:17:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:17:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:17:17 INFO - 'tests': ['basic_compositor_video']}, 09:17:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:17:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:17:17 INFO - 'tests': ['a11yr', 09:17:17 INFO - 'ts_paint', 09:17:17 INFO - 'tpaint', 09:17:17 INFO - 'sessionrestore', 09:17:17 INFO - 'sessionrestore_no_auto_restore', 09:17:17 INFO - 'tabpaint']}, 09:17:17 INFO - 'other-e10s': {'tests': ['a11yr', 09:17:17 INFO - 'ts_paint', 09:17:17 INFO - 'tpaint', 09:17:17 INFO - 'sessionrestore', 09:17:17 INFO - 'sessionrestore_no_auto_restore', 09:17:17 INFO - 'tabpaint']}, 09:17:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:17:17 INFO - 'ts_paint', 09:17:17 INFO - 'tpaint', 09:17:17 INFO - 'sessionrestore', 09:17:17 INFO - 'sessionrestore_no_auto_restore', 09:17:17 INFO - 'tabpaint']}, 09:17:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:17:17 INFO - 'ts_paint', 09:17:17 INFO - 'tpaint', 09:17:17 INFO - 'sessionrestore', 09:17:17 INFO - 'sessionrestore_no_auto_restore', 09:17:17 INFO - 'tabpaint']}, 09:17:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:17:17 INFO - 'tests': ['a11yr', 09:17:17 INFO - 'ts_paint', 09:17:17 INFO - 'tpaint', 09:17:17 INFO - 'sessionrestore', 09:17:17 INFO - 'sessionrestore_no_auto_restore', 09:17:17 INFO - 'tabpaint']}, 09:17:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:17:17 INFO - 'tests': ['a11yr', 09:17:17 INFO - 'ts_paint', 09:17:17 INFO - 'tpaint', 09:17:17 INFO - 'sessionrestore', 09:17:17 INFO - 'sessionrestore_no_auto_restore', 09:17:17 INFO - 'tabpaint']}, 09:17:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:17:17 INFO - 'tests': ['tsvgx', 09:17:17 INFO - 'tsvgr_opacity', 09:17:17 INFO - 'tart', 09:17:17 INFO - 'tscrollx', 09:17:17 INFO - 'cart']}, 09:17:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:17:17 INFO - 'tsvgr_opacity', 09:17:17 INFO - 'tart', 09:17:17 INFO - 'tscrollx', 09:17:17 INFO - 'cart']}, 09:17:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:17 INFO - 'talos_options': ['--disable-e10s'], 09:17:17 INFO - 'tests': ['tp5o']}, 09:17:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:17 INFO - 'tests': ['tp5o']}, 09:17:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:17 INFO - 'talos_options': ['--disable-e10s', 09:17:17 INFO - '--xperf_path', 09:17:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:17 INFO - 'tests': ['tp5n']}, 09:17:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:17 INFO - 'talos_options': ['--xperf_path', 09:17:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:17 INFO - 'tests': ['tp5n']}}, 09:17:17 INFO - 'talos.zip': {'path': '', 09:17:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:17:17 INFO - Running post-action listener: _resource_record_post_action 09:17:17 INFO - [mozharness: 2016-10-07 16:17:17.093402Z] Finished populate-webroot step (success) 09:17:17 INFO - [mozharness: 2016-10-07 16:17:17.093539Z] Running create-virtualenv step. 09:17:17 INFO - Running pre-action listener: _resource_record_pre_action 09:17:17 INFO - Running main action method: create_virtualenv 09:17:17 INFO - Creating virtualenv /builds/slave/test/build/venv 09:17:17 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 09:17:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:17:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:17:17 INFO - Using real prefix '/tools/python27' 09:17:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:17:17 INFO - Installing distribute.............................................................................................................................................................................................done. 09:17:20 INFO - Installing pip.................done. 09:17:20 INFO - Return code: 0 09:17:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:17:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:17:21 INFO - Reading from file tmpfile_stdout 09:17:21 INFO - Output received: 09:17:21 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) 09:17:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:21 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 0x10c696490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c60c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe42ad8a640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4aad30>, '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 0x10c2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a2b20>, '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.kgMleUVsEB/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.TA6MAjnkqL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 09:17:21 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 09:17:21 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 09:17:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgMleUVsEB/Render', 09:17:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:21 INFO - 'HOME': '/Users/cltbld', 09:17:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:21 INFO - 'LOGNAME': 'cltbld', 09:17:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:21 INFO - 'MOZ_NO_REMOTE': '1', 09:17:21 INFO - 'NO_EM_RESTART': '1', 09:17:21 INFO - 'PAGER': '/bin/cat', 09:17:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:21 INFO - 'PWD': '/builds/slave/test', 09:17:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:21 INFO - 'SHELL': '/bin/bash', 09:17:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TA6MAjnkqL/Listeners', 09:17:21 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 09:17:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:21 INFO - 'USER': 'cltbld', 09:17:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:21 INFO - 'XPC_FLAGS': '0x0', 09:17:21 INFO - 'XPC_SERVICE_NAME': '0', 09:17:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:21 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 09:17:21 INFO - Cleaning up... 09:17:21 INFO - Return code: 0 09:17:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:21 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 0x10c696490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c60c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe42ad8a640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4aad30>, '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 0x10c2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a2b20>, '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.kgMleUVsEB/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.TA6MAjnkqL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 09:17:21 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 09:17:21 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 09:17:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgMleUVsEB/Render', 09:17:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:21 INFO - 'HOME': '/Users/cltbld', 09:17:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:21 INFO - 'LOGNAME': 'cltbld', 09:17:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:21 INFO - 'MOZ_NO_REMOTE': '1', 09:17:21 INFO - 'NO_EM_RESTART': '1', 09:17:21 INFO - 'PAGER': '/bin/cat', 09:17:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:21 INFO - 'PWD': '/builds/slave/test', 09:17:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:21 INFO - 'SHELL': '/bin/bash', 09:17:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TA6MAjnkqL/Listeners', 09:17:21 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 09:17:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:21 INFO - 'USER': 'cltbld', 09:17:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:21 INFO - 'XPC_FLAGS': '0x0', 09:17:21 INFO - 'XPC_SERVICE_NAME': '0', 09:17:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:21 INFO - Downloading/unpacking psutil>=3.1.1 09:17: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 09:17: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 09:17: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 09:17: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 09:17: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 09:17: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 09:17:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:17:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:24 INFO - Installing collected packages: psutil 09:17:24 INFO - Running setup.py install for psutil 09:17:25 INFO - building 'psutil._psutil_osx' extension 09:17:25 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 09:17:25 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 09:17:25 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 09:17:25 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 09:17:25 INFO - building 'psutil._psutil_posix' extension 09:17:25 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 09:17:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:17:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:17:25 INFO - ^ 09:17:26 INFO - 1 warning generated. 09:17:26 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 09:17:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:26 INFO - Successfully installed psutil 09:17:26 INFO - Cleaning up... 09:17:26 INFO - Return code: 0 09:17:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c696490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c60c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe42ad8a640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4aad30>, '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 0x10c2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a2b20>, '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.kgMleUVsEB/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.TA6MAjnkqL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 09:17:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:17:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgMleUVsEB/Render', 09:17:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:26 INFO - 'HOME': '/Users/cltbld', 09:17:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:26 INFO - 'LOGNAME': 'cltbld', 09:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:26 INFO - 'MOZ_NO_REMOTE': '1', 09:17:26 INFO - 'NO_EM_RESTART': '1', 09:17:26 INFO - 'PAGER': '/bin/cat', 09:17:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:26 INFO - 'PWD': '/builds/slave/test', 09:17:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:26 INFO - 'SHELL': '/bin/bash', 09:17:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TA6MAjnkqL/Listeners', 09:17:26 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 09:17:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:26 INFO - 'USER': 'cltbld', 09:17:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:26 INFO - 'XPC_FLAGS': '0x0', 09:17:26 INFO - 'XPC_SERVICE_NAME': '0', 09:17:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:17:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:17:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:17:29 INFO - Installing collected packages: mozsystemmonitor 09:17:29 INFO - Running setup.py install for mozsystemmonitor 09:17:29 INFO - Successfully installed mozsystemmonitor 09:17:29 INFO - Cleaning up... 09:17:29 INFO - Return code: 0 09:17:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:17:29 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')]} 09:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:29 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')]} 09:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:29 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 0x10c696490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c60c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe42ad8a640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4aad30>, '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 0x10c2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a2b20>, '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.kgMleUVsEB/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.TA6MAjnkqL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 09:17:29 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 09:17:29 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 09:17:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgMleUVsEB/Render', 09:17:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:29 INFO - 'HOME': '/Users/cltbld', 09:17:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:29 INFO - 'LOGNAME': 'cltbld', 09:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:29 INFO - 'MOZ_NO_REMOTE': '1', 09:17:29 INFO - 'NO_EM_RESTART': '1', 09:17:29 INFO - 'PAGER': '/bin/cat', 09:17:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:29 INFO - 'PWD': '/builds/slave/test', 09:17:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:29 INFO - 'SHELL': '/bin/bash', 09:17:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TA6MAjnkqL/Listeners', 09:17:29 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 09:17:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:29 INFO - 'USER': 'cltbld', 09:17:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:29 INFO - 'XPC_FLAGS': '0x0', 09:17:29 INFO - 'XPC_SERVICE_NAME': '0', 09:17:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:29 INFO - Downloading/unpacking jsonschema==2.5.1 09:17: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 09:17: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 09:17: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 09:17: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 09:17: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 09:17: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 09:17:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:17:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:32 INFO - Downloading functools32-3.2.3-2.tar.gz 09:17:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:17:32 INFO - warning: no files found matching '*.txt' 09:17:32 INFO - no previously-included directories found matching 'build' 09:17:32 INFO - no previously-included directories found matching 'dist' 09:17:32 INFO - no previously-included directories found matching '.git*' 09:17:32 INFO - Installing collected packages: jsonschema, functools32 09:17:32 INFO - Running setup.py install for functools32 09:17:32 INFO - warning: no files found matching '*.txt' 09:17:32 INFO - no previously-included directories found matching 'build' 09:17:32 INFO - no previously-included directories found matching 'dist' 09:17:32 INFO - no previously-included directories found matching '.git*' 09:17:32 INFO - Successfully installed jsonschema functools32 09:17:32 INFO - Cleaning up... 09:17:32 INFO - Return code: 0 09:17:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:17: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')]} 09:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17: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')]} 09:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c696490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c60c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe42ad8a640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4aad30>, '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 0x10c2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a2b20>, '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.kgMleUVsEB/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.TA6MAjnkqL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 09:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:17:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgMleUVsEB/Render', 09:17:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:32 INFO - 'HOME': '/Users/cltbld', 09:17:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:32 INFO - 'LOGNAME': 'cltbld', 09:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:32 INFO - 'MOZ_NO_REMOTE': '1', 09:17:32 INFO - 'NO_EM_RESTART': '1', 09:17:32 INFO - 'PAGER': '/bin/cat', 09:17:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:32 INFO - 'PWD': '/builds/slave/test', 09:17:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:32 INFO - 'SHELL': '/bin/bash', 09:17:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TA6MAjnkqL/Listeners', 09:17:32 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 09:17:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:32 INFO - 'USER': 'cltbld', 09:17:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:32 INFO - 'XPC_FLAGS': '0x0', 09:17:32 INFO - 'XPC_SERVICE_NAME': '0', 09:17:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:17:33 INFO - Cleaning up... 09:17:33 INFO - Return code: 0 09:17:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:17: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')]} 09:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17: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')]} 09:17:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17: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', '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 0x10c696490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c60c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe42ad8a640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4aad30>, '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 0x10c2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a2b20>, '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.kgMleUVsEB/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.TA6MAjnkqL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 09:17: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:17: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 blobuploader==1.2.4 09:17:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgMleUVsEB/Render', 09:17:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:33 INFO - 'HOME': '/Users/cltbld', 09:17:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:33 INFO - 'LOGNAME': 'cltbld', 09:17:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:33 INFO - 'MOZ_NO_REMOTE': '1', 09:17:33 INFO - 'NO_EM_RESTART': '1', 09:17:33 INFO - 'PAGER': '/bin/cat', 09:17:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:33 INFO - 'PWD': '/builds/slave/test', 09:17:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:33 INFO - 'SHELL': '/bin/bash', 09:17:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TA6MAjnkqL/Listeners', 09:17:33 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 09:17:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:33 INFO - 'USER': 'cltbld', 09:17:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:33 INFO - 'XPC_FLAGS': '0x0', 09:17:33 INFO - 'XPC_SERVICE_NAME': '0', 09:17:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:33 INFO - Downloading/unpacking blobuploader==1.2.4 09:17:33 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 09:17:33 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 09:17:33 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 09:17:33 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 09:17:33 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 09:17:33 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 09:17:35 INFO - Downloading blobuploader-1.2.4.tar.gz 09:17:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:17:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:17:36 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 09:17:36 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 09:17:36 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 09:17:36 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 09:17:36 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 09:17:36 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 09:17:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:17:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:17:36 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 09:17:36 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 09:17:36 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 09:17:36 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 09:17:36 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 09:17:36 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 09:17:37 INFO - Downloading docopt-0.6.1.tar.gz 09:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:17:37 INFO - Installing collected packages: blobuploader, requests, docopt 09:17:37 INFO - Running setup.py install for blobuploader 09:17:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:17:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:17:37 INFO - Running setup.py install for requests 09:17:37 INFO - Running setup.py install for docopt 09:17:38 INFO - Successfully installed blobuploader requests docopt 09:17:38 INFO - Cleaning up... 09:17:38 INFO - Return code: 0 09:17:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:38 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 0x10c696490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c60c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe42ad8a640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4aad30>, '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 0x10c2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a2b20>, '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.kgMleUVsEB/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.TA6MAjnkqL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 09:17:38 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 09:17:38 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 09:17:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgMleUVsEB/Render', 09:17:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:38 INFO - 'HOME': '/Users/cltbld', 09:17:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:38 INFO - 'LOGNAME': 'cltbld', 09:17:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:38 INFO - 'MOZ_NO_REMOTE': '1', 09:17:38 INFO - 'NO_EM_RESTART': '1', 09:17:38 INFO - 'PAGER': '/bin/cat', 09:17:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:38 INFO - 'PWD': '/builds/slave/test', 09:17:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:38 INFO - 'SHELL': '/bin/bash', 09:17:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TA6MAjnkqL/Listeners', 09:17:38 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 09:17:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:38 INFO - 'USER': 'cltbld', 09:17:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:38 INFO - 'XPC_FLAGS': '0x0', 09:17:38 INFO - 'XPC_SERVICE_NAME': '0', 09:17:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:38 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-tCI1xm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:38 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-YwcF2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:38 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-hL9trd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:38 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-FzgypP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:38 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-YU2mUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:38 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-dpy1g3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:39 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-nMyTMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:39 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-4vtPIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:39 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-WmQ4p0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:39 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-qURNw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:39 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-asgenu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:39 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-NvHsdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:39 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-Fz2Sm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:39 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-_JsX8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:39 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-YIDB6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:39 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-JNctbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:40 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-SJSfL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:17:40 INFO - Running setup.py install for manifestparser 09:17:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:17:40 INFO - Running setup.py install for mozcrash 09:17:40 INFO - Running setup.py install for mozdebug 09:17:40 INFO - Running setup.py install for mozdevice 09:17:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:17:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:17:40 INFO - Running setup.py install for mozfile 09:17:40 INFO - Running setup.py install for mozhttpd 09:17:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:17:41 INFO - Running setup.py install for mozinfo 09:17:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:17:41 INFO - Running setup.py install for mozInstall 09:17:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:17:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:17:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:17:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:17:41 INFO - Running setup.py install for mozleak 09:17:41 INFO - Running setup.py install for mozlog 09:17:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:17:41 INFO - Running setup.py install for moznetwork 09:17:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:17:41 INFO - Running setup.py install for mozprocess 09:17:41 INFO - Running setup.py install for mozprofile 09:17:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:17:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:17:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:17:42 INFO - Running setup.py install for mozrunner 09:17:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:17:42 INFO - Running setup.py install for mozscreenshot 09:17:42 INFO - Running setup.py install for moztest 09:17:42 INFO - Running setup.py install for mozversion 09:17:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:17:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:17:42 INFO - Cleaning up... 09:17:42 INFO - Return code: 0 09:17:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17: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')]} 09:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17: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')]} 09:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:42 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 0x10c696490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c60c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe42ad8a640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4aad30>, '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 0x10c2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a2b20>, '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.kgMleUVsEB/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.TA6MAjnkqL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 09:17:42 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 09:17:42 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 09:17:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgMleUVsEB/Render', 09:17:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:42 INFO - 'HOME': '/Users/cltbld', 09:17:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:42 INFO - 'LOGNAME': 'cltbld', 09:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:42 INFO - 'MOZ_NO_REMOTE': '1', 09:17:42 INFO - 'NO_EM_RESTART': '1', 09:17:42 INFO - 'PAGER': '/bin/cat', 09:17:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:42 INFO - 'PWD': '/builds/slave/test', 09:17:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:42 INFO - 'SHELL': '/bin/bash', 09:17:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TA6MAjnkqL/Listeners', 09:17:42 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 09:17:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:42 INFO - 'USER': 'cltbld', 09:17:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:42 INFO - 'XPC_FLAGS': '0x0', 09:17:42 INFO - 'XPC_SERVICE_NAME': '0', 09:17:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-YTMgj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:43 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)) 09:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-MC0_vC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-5xl4II-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:43 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)) 09:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-t_LB6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:43 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)) 09:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-AQuj0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:43 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)) 09:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-tUW4iu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:43 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)) 09:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-9RUFxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:43 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)) 09:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-58Yulf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:43 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)) 09:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-kf4axb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:43 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)) 09:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-T_7Rwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:44 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)) 09:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:44 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-rENnOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:44 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)) 09:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:44 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-1XiMhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:44 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)) 09:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:44 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-EE0ijd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:44 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)) 09:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:44 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-y2Ixiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:44 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-3k0wEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:44 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)) 09:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:44 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-nSFUar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:44 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)) 09:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:44 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-fHPqur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:44 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)) 09:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:17:44 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)) 09:17:44 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)) 09:17:44 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)) 09:17:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:47 INFO - Downloading blessings-1.6.tar.gz 09:17:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:17:47 INFO - Installing collected packages: blessings 09:17:47 INFO - Running setup.py install for blessings 09:17:47 INFO - Successfully installed blessings 09:17:47 INFO - Cleaning up... 09:17:47 INFO - Return code: 0 09:17:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:17:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:47 INFO - Reading from file tmpfile_stdout 09:17:47 INFO - Current package versions: 09:17:47 INFO - blessings == 1.6 09:17:47 INFO - blobuploader == 1.2.4 09:17:47 INFO - docopt == 0.6.1 09:17:47 INFO - functools32 == 3.2.3-2 09:17:47 INFO - jsonschema == 2.5.1 09:17:47 INFO - manifestparser == 1.1 09:17:47 INFO - mozInstall == 1.12 09:17:47 INFO - mozcrash == 0.17 09:17:47 INFO - mozdebug == 0.1 09:17:47 INFO - mozdevice == 0.48 09:17:47 INFO - mozfile == 1.2 09:17:47 INFO - mozhttpd == 0.7 09:17:47 INFO - mozinfo == 0.9 09:17:47 INFO - mozleak == 0.1 09:17:47 INFO - mozlog == 3.4 09:17:47 INFO - moznetwork == 0.27 09:17:47 INFO - mozprocess == 0.23 09:17:47 INFO - mozprofile == 0.28 09:17:47 INFO - mozrunner == 6.12 09:17:47 INFO - mozscreenshot == 0.1 09:17:47 INFO - mozsystemmonitor == 0.3 09:17:47 INFO - moztest == 0.7 09:17:47 INFO - mozversion == 1.4 09:17:47 INFO - psutil == 3.1.1 09:17:47 INFO - requests == 1.2.3 09:17:47 INFO - wsgiref == 0.1.2 09:17:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:48 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 0x10c696490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c60c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe42ad8a640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4aad30>, '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 0x10c2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a2b20>, '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.kgMleUVsEB/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.TA6MAjnkqL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 09:17:48 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 09:17:48 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 09:17:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgMleUVsEB/Render', 09:17:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:48 INFO - 'HOME': '/Users/cltbld', 09:17:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:48 INFO - 'LOGNAME': 'cltbld', 09:17:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:48 INFO - 'MOZ_NO_REMOTE': '1', 09:17:48 INFO - 'NO_EM_RESTART': '1', 09:17:48 INFO - 'PAGER': '/bin/cat', 09:17:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:48 INFO - 'PWD': '/builds/slave/test', 09:17:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:48 INFO - 'SHELL': '/bin/bash', 09:17:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TA6MAjnkqL/Listeners', 09:17:48 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 09:17:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:48 INFO - 'USER': 'cltbld', 09:17:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:48 INFO - 'XPC_FLAGS': '0x0', 09:17:48 INFO - 'XPC_SERVICE_NAME': '0', 09:17:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:48 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)) 09:17:48 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)) 09:17:48 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)) 09:17:48 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)) 09:17:48 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)) 09:17:48 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)) 09:17:48 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)) 09:17:48 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)) 09:17:48 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)) 09:17:48 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)) 09:17:48 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)) 09:17:48 INFO - Cleaning up... 09:17:49 INFO - Return code: 0 09:17:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:17:49 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')]} 09:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:49 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')]} 09:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:49 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 0x10c696490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c60c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe42ad8a640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4aad30>, '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 0x10c2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a2b20>, '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.kgMleUVsEB/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.TA6MAjnkqL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 09:17:49 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 09:17:49 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 09:17:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgMleUVsEB/Render', 09:17:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:49 INFO - 'HOME': '/Users/cltbld', 09:17:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:49 INFO - 'LOGNAME': 'cltbld', 09:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:49 INFO - 'MOZ_NO_REMOTE': '1', 09:17:49 INFO - 'NO_EM_RESTART': '1', 09:17:49 INFO - 'PAGER': '/bin/cat', 09:17:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:49 INFO - 'PWD': '/builds/slave/test', 09:17:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:49 INFO - 'SHELL': '/bin/bash', 09:17:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TA6MAjnkqL/Listeners', 09:17:49 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 09:17:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:49 INFO - 'USER': 'cltbld', 09:17:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:49 INFO - 'XPC_FLAGS': '0x0', 09:17:49 INFO - 'XPC_SERVICE_NAME': '0', 09:17:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:17:49 INFO - Cleaning up... 09:17:49 INFO - Return code: 0 09:17:49 INFO - Running post-action listener: _resource_record_post_action 09:17:49 INFO - Running post-action listener: _start_resource_monitoring 09:17:49 INFO - Starting resource monitoring. 09:17:49 INFO - [mozharness: 2016-10-07 16:17:49.708490Z] Finished create-virtualenv step (success) 09:17:49 INFO - [mozharness: 2016-10-07 16:17:49.709562Z] Running install step. 09:17:49 INFO - Running pre-action listener: _resource_record_pre_action 09:17:49 INFO - Running main action method: install 09:17:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:49 INFO - Reading from file tmpfile_stdout 09:17:49 INFO - Detecting whether we're running mozinstall >=1.0... 09:17:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:17:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:17:50 INFO - Reading from file tmpfile_stdout 09:17:50 INFO - Output received: 09:17:50 INFO - Usage: mozinstall [options] installer 09:17:50 INFO - Options: 09:17:50 INFO - -h, --help show this help message and exit 09:17:50 INFO - -d DEST, --destination=DEST 09:17:50 INFO - Directory to install application into. [default: 09:17:50 INFO - "/builds/slave/test"] 09:17:50 INFO - --app=APP Application being installed. [default: firefox] 09:17:50 INFO - mkdir: /builds/slave/test/build/application 09:17:50 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'] 09:17:50 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 09:18:24 INFO - Reading from file tmpfile_stdout 09:18:24 INFO - Output received: 09:18:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:18:24 INFO - Running post-action listener: _resource_record_post_action 09:18:24 INFO - [mozharness: 2016-10-07 16:18:24.467299Z] Finished install step (success) 09:18:24 INFO - [mozharness: 2016-10-07 16:18:24.467429Z] Running run-tests step. 09:18:24 INFO - Running pre-action listener: _resource_record_pre_action 09:18:24 INFO - Running main action method: run_tests 09:18:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:18:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:18:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:18:24 INFO - 2016-10-07 09:18:24.481 screenresolution[1642:7442] starting screenresolution argv=screenresolution get 09:18:24 INFO - 2016-10-07 09:18:24.484 screenresolution[1642:7442] Display 0: 1600x1200x32@60 09:18:24 INFO - 2016-10-07 09:18:24.490 screenresolution[1643:7444] starting screenresolution argv=screenresolution list 09:18:24 INFO - Available Modes on Display 0 09:18:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:18:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:18:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:18:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:18:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:18:24 INFO - Intel Iris: 09:18:24 INFO - Chipset Model: Intel Iris 09:18:24 INFO - Type: GPU 09:18:24 INFO - Bus: Built-In 09:18:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:18:24 INFO - Vendor: Intel (0x8086) 09:18:24 INFO - Device ID: 0x0a2e 09:18:24 INFO - Revision ID: 0x0009 09:18:24 INFO - Displays: 09:18:24 INFO - Display: 09:18:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:18:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:18:24 INFO - Display Serial Number: 1600x1200 60 09:18:24 INFO - Main Display: Yes 09:18:24 INFO - Mirror: Off 09:18:24 INFO - Online: Yes 09:18:24 INFO - Rotation: Supported 09:18:24 INFO - Return code: 0 09:18:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:18:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:18:24 INFO - Python 2.7.3 09:18:24 INFO - Return code: 0 09:18:24 INFO - grabbing minidump binary from tooltool 09:18:24 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')]} 09:18:24 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 0x10c4aad30>, '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 0x10c2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4a2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:18:24 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 09:18:24 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 09:18:24 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 09:18:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:18:24 INFO - Return code: 0 09:18:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:18:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:18:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:18:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:18:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:18:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0152', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:18:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0152 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:18:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgMleUVsEB/Render', 09:18:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:24 INFO - 'HOME': '/Users/cltbld', 09:18:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:24 INFO - 'LOGNAME': 'cltbld', 09:18:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:18:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:18:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:24 INFO - 'MOZ_NO_REMOTE': '1', 09:18:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:18:24 INFO - 'NO_EM_RESTART': '1', 09:18:24 INFO - 'PAGER': '/bin/cat', 09:18:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:24 INFO - 'PWD': '/builds/slave/test', 09:18:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:18:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:24 INFO - 'SHELL': '/bin/bash', 09:18:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TA6MAjnkqL/Listeners', 09:18:24 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 09:18:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:24 INFO - 'USER': 'cltbld', 09:18:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:24 INFO - 'XPC_FLAGS': '0x0', 09:18:24 INFO - 'XPC_SERVICE_NAME': '0', 09:18:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0152', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:18:25 INFO - mozversion application_buildid: 20161006150000 09:18:25 INFO - mozversion application_changeset: d8e1f5cf0a70a53e8a5532809096a0a5bf729196 09:18:25 INFO - mozversion application_display_name: Nightly 09:18:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:18:25 INFO - mozversion application_name: Firefox 09:18:25 INFO - mozversion application_remotingname: firefox 09:18:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:18:25 INFO - mozversion application_vendor: Mozilla 09:18:25 INFO - mozversion application_version: 52.0a1 09:18:25 INFO - mozversion platform_buildid: 20161006150000 09:18:25 INFO - mozversion platform_changeset: d8e1f5cf0a70a53e8a5532809096a0a5bf729196 09:18:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:18:25 INFO - mozversion platform_version: 52.0a1 09:18:25 INFO - using testdate: 1475857105 09:18:25 INFO - actual date: 1475857105 09:18:25 INFO - starting webserver on 'localhost:49280' 09:18:25 INFO - SUITE-START | Running 2 tests 09:18:25 INFO - TEST-START | dromaeo_css 09:18:25 INFO - operating with platform_type : mac_ 09:18:25 INFO - Initialising browser for dromaeo_css test... 09:18:25 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmp8KXKRV/profile http://localhost:49280/getInfo.html) 09:18:27 INFO - PROCESS | 1653 | 2016-10-07 09:18:27.895 plugin-container[1655:7607] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 09:18:27 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:18:27 INFO - PROCESS | 1653 | 2016-10-07 09:18:27.898 plugin-container[1655:7607] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b17, name = 'com.apple.CFPasteboardClient' 09:18:27 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:18:27 INFO - PROCESS | 1653 | 2016-10-07 09:18:27.898 plugin-container[1655:7607] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:18:27 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 09:18:27 INFO - PROCESS | 1653 | colorDepth:24 09:18:27 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 09:18:27 INFO - PROCESS | 1653 | __metrics 09:18:28 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 09:18:28 INFO - TEST-INFO | 1653: exit 0 09:18:28 INFO - Browser initialized. 09:18:28 INFO - Running cycle 1/1 for dromaeo_css test... 09:18:28 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmp8KXKRV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:18:31 INFO - PROCESS | 1659 | 2016-10-07 09:18:31.155 plugin-container[1660:7786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b1b, name = 'com.apple.tsm.portname' 09:18:31 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:18:31 INFO - PROCESS | 1659 | 2016-10-07 09:18:31.162 plugin-container[1660:7786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9007, name = 'com.apple.CFPasteboardClient' 09:18:31 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:18:31 INFO - PROCESS | 1659 | 2016-10-07 09:18:31.162 plugin-container[1660:7786] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:18:40 INFO - PROCESS | 1659 | RSS: Main: 240271360 09:18:40 INFO - PROCESS | 1659 | 09:19:30 INFO - PROCESS | 1659 | 1475857170363 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] 09:21:43 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 09:21:45 INFO - PROCESS | 1659 | RSS: Main: 253001728 09:21:45 INFO - PROCESS | 1659 | 09:24:47 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 09:24:48 INFO - PROCESS | 1659 | RSS: Main: 265666560 09:24:48 INFO - PROCESS | 1659 | 09:27:50 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 09:27:50 INFO - PROCESS | 1659 | RSS: Main: 270290944 09:27:50 INFO - PROCESS | 1659 | 09:30:52 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 09:30:53 INFO - PROCESS | 1659 | RSS: Main: 269860864 09:30:53 INFO - PROCESS | 1659 | 09:33:56 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 09:33:56 INFO - PROCESS | 1659 | RSS: Main: 276520960 09:33:56 INFO - PROCESS | 1659 | 09:36:59 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 09:36:59 INFO - PROCESS | 1659 | RSS: Main: 279302144 09:36:59 INFO - PROCESS | 1659 | 09:36:59 INFO - PROCESS | 1659 | __start_tp_report 09:36:59 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 09:36:59 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 09:36:59 INFO - PROCESS | 1659 | |i|pagename|runs| 09:36:59 INFO - PROCESS | 1659 | |0;dojo.html;2609.6737907761535;2845.5020371435;2968.896088636898;3369.326134773046;3370.0226934189072;4670.532946705336;5002.6992981824715;5232.3197984262;5715.799947001856;5722.513586345161;5560.499555040045;5621;5674.489295963363;5693.715314234282;5716.8856622867515;5102.464241254671;5223.738813059351;5266.420693723688;5275.762590683428;5293.550048245904;5276.92084618731;5280.683159010452;5302.840914772543;5317.7607007684555;5354.518093371596;4512.7743612819395;5086.211637196222;5280.683159010452;5300.840974770763;5301.7084060376665;5266.657667251617;5292.52367286946;5300.575953923677;5301.469853014687;5302.1781623848365;4391.670624703128;4905.583025442842;5138.229265610151;5245.763940622643;5261.842144735664;5664.150377443375;5765.942340576583;5811.244538210048;5850.444207800252;5889.998700220973;5566.332040155166;5901.675407852566;6188.978818494943;6211.875762484725;6223.968880155616;6108.797423689706;6143.849652621591;6166.506679465632;6167.599106058136;6206.2862770781485;5914.615549989237;5917.14201440793;5943.494802941798;5968.67172305523;5972.283326000865;6261.655608941552;6264.718087686065;6270.811875643647;6286.4342209201395;6289.654069026247;6020.397960204032;6030.608010479393;6120;6181.721822517998;6193.566450348432;6116.633002019893;6131.420293029677;6133.877322453526;6140.473573783541;6168.907466388007;6198.659073750897;6246.187995560639;6253.812385628438;6261.248650161919;6511.0558968949235;6132.355508992545;6144.293406258337;6182.938170618237;6221.875562488815;6248.5313601480075;4946.455889852114;4967.5032496750755;4974.651774375831;4980.203167493184;5020.824271150492;3934.390169523728;4437.134758722083;4446.444194475691;4447.110577884436;4448.822047118079;4646.233371493666;5779.422057794187;5810.912836307459;5814.04068328721;5856.092305692625;6173.753049878044;6201.7519299228425;6205.596636218631;6211.130441738161;6232.439080482778;5920.881582368328;5937.050071988461;5952.970235148797;5961.076033214858;5993;5331.733413329318;5356.839294821161;5370.597205209625;5381.973090134525;5414.648047876875;5276.81531146408;6346.619202847844;6351.428371446592;6363.586366886323;6368.745250189941;6153.61588544922;6187.102870083902;6206.007038873759;6213.627182369072;6217.84969780592;6133.610107582747;6193.628382297076;6226.1905952225925;6251.656158911122;6301.747930082745;6111.895622426936;6189.907151392643;6198.380161983766;6206.131141640084;6223.253209614875;5154.664946778447;5325.0148722486465;6058.636481811105;6111.388861114031;6192.690365481798;5961.970190149022;6109.389061093855;6115.3578874217565;6156.630602163885;6210.937890621036;6126.66303353302;6137.232845894263;6156.353582873734;6173.59871608344;6195.4424101831055;5365.570754339566;5975.312839023568;6053.842934858572;6064.545159112996;6081.756729730761;5983.820485385445;6117.296510901126;6130.47890929276;6153.907691384722;6172.043333283348;6003.3996600339615;6013.338532761305;6049.637021778707;6083.117947897442;6116.235470566179 09:36:59 INFO - PROCESS | 1659 | |1;ext.html;449.35293177823934;512.5259135299848;531.7212104887744;533.847853361792;546.1589152704835;30631.855770096074;39683.60316396835;40259.59740402592;42306.788466057704;42435;41572.58427415725;41684.583154168526;41988;41989.95025124373;41993.37009944853;9556.761080972961;9655.903440965605;9743.464109473976;9758.951205243999;9759.02409759022;9552.140307372336;9569.377990430634;9632.80734385312;9648.565814538328;9677.27420443466;9658.985806490322;9684.70945871625;9696.981816909229;9700.320977531575;9707.805843883118;9615.56729947154;9638.469884156368;9669.661561845336;9677.564509597085;9708.07773261546;9485.715428537154;9546.474943878084;9557.474338911357;9560.043995600454;9566.13904748576;33216.0035198942;38543;39377;39456;39698.404523932164;3074.1546074829375;3361.932761344784;3411.3347897160065;3498.6151523332414;3555.982220088883;28516.71483285182;30175.245618859484;30582;30986.535201971987;31379;3211.5022171563446;3220.6940340667597;3227.6126864776143;3317.1541256979317;3320.5019247112887;414585.999999997;416714;417555;419772;421373;59155;59227.70386148042;59232;59579.10631340533;59696.40303596995;61658.69170654118;61785.382146177966;61880.69059654673;62123.37876621266;62332.68833655803;11631.011364033982;15828.683426331409;15833.366665333258;15903.761443578356;16141.435049773201;12361.196522226026;12393.946514546184;12435.191712538655;12491.938185254174;12496.875031249752;16203.703703703675;17525.036123013357;18243.17905694247;18283.63432731338;18288.725669114974;3206.198450387403;7207.819804504929;8038.132163520662;8054.436189466681;8075.030996280484;869.6564856881496;1002.6340385759146;1020.7142000239945;1062.516554967488;1073.393532654048;27938.441231175264;28968.420631587673;29259;29865.402691946474;30063.699363006526;929.9814003719888;935.8923723771717;1096.5230124895695;1116.8045592021526;1128.8814674459227;12724.1729287596;12738.936305318599;14808.3336249869;15134.243287835565;15304.923475382551;555006;586500;650762;654532.7273363698;656521;55170.89658206813;55788.884222315326;55866.44133558694;55874.32377028696;56269.43730562724;1068.460427484131;1365.8497565267812;1378.3659516622265;1384.0657556149558;1390.1103293891917;22773.658395124417;25015.87492062528;27085.729142709108;29299;30081.69918300789;46197;46911.29633055439;47300.52699472961;47418.76290618525;47428;438128;524338;603506;605166.9483305287;609581;25553.23340299794;25815.483690326462;26220.73779262183;26222;26246.34384140412;44641;45651;45839.76225795398;46143.769281154724;47043.52956470392;30556.541651875683;31570.526442103845;37585.81207093947;37588.436173457965;38650.03374915649;27294.590581141696;35173.12067198341;37396.81301593475;38172.80913595414;38619.80690096531;36567.902962911154;36587.268254634226;37437.43843842399;37569.87290381293;37831.86504404762;31553.842230788698;33571.83214083914;33762.662373375955;33957.49063764095;34815.95552133339;24824.37939051466;24881.502369952137;25115;25366.365840854127;25405.74594254034 09:36:59 INFO - PROCESS | 1659 | |2;jquery.html;341.6651681352275;366.69747458346853;372.07353689313607;382.5409508589693;396.65689178860293;19641.312554060594;20037.897915614652;20854.99999999998;20880.269190578332;21084.894575527138;11457.713557161074;11811.940940295286;11826.349550774705;11850.585229516968;13576.932115339434;5114.539691427771;5434.103372943479;5439.646422982513;5439.755211015514;5468.070428027245;3874.6319099685484;4073.30753771858;5075.6447048706605;5451.972740136293;6039.245094363205;6065.969670151643;6097.8170654880205;6114.327423983358;6124.540659450537;6131.110988906606;5813.389594092623;5941.465268125889;6012.96993515034;6059.969700151515;6063.302720187146;5495.560355171577;5791.710414479259;5796.97101514494;5847.0352391855295;5874.676892770896;13024.86975130246;13065.673358166121;13101.279429631366;13101.279429631366;16384;2128.4146859613575;2253.571821353954;2327.0924339507606;2529.4733239759403;2717.8097533172686;8068.3948703847445;9884;10105.141063009652;10319.226058045677;13292.800607990888;1850.1119462658;1926;1929.1511734836627;1979.6238714644317;1979.8218160365636;107635.46182269117;116760;116919;117558.23662645153;117716;9098.727038188865;11385.430728463543;11388.259763115371;11542.19204655683;11645.941770291094;14167.291635418187;14328.85671143296;14395.208263545606;14408.567742967727;14428.350724217435;8128.878066829002;8374.036985746718;8418.536980466133;8481.279091277187;8515.40392172542;7289.963550182321;7843.411744119214;8288.50268983863;8290.253877151084;8406.327493800482;4480.529544397857;4512.646206138211;4515.074409745994;4553.123057646599;4630.606398456169;1374.6357215337944;1410.4358256697403;1575.9290831912592;1618.5387164658016;2007.9397618071482;869.852125138728;875.3259989807813;914.5884352041608;925.3846192282057;931.7810314576069;20017.59964800696;20770.896145519175;20878.686819697716;20986.580268394548;21100.05049772764;867.7591044805988;888.3337496877342;902.6576035188675;940.5297351324338;1012.4937531234383;7456.813579660552;9867.950660246797;10619.884912084275;10620.681379558626;11073.003429691367;551883.2405837945;560328;597393;604303;609063;95459;124026;130938.34530827476;132505;132758;947.1428357336621;952.1335584618103;982.1700662939804;983.3902980152351;985.8718366612294;16875.578110547336;17285.65428691394;18217.089145542934;18583.854158860486;18825;36816.26367472689;43963.56036439595;54438;55713.60715982133;57755.71122144531;83705.58147209225;83873;84089.73865392146;84504;85152;5858.882822343615;6918.654067296515;7093.113360829896;7344.853102937805;7412.555246685216;8980.506072166094;11993.460294286604;12254.142210045211;12485.188462749893;12693.555725549562;10538.473076346305;10970.780584388109;11117.829000294927;11136.49885755159;11194;10561.471926403496;10790.67627971162;11024.283421577571;11029.889701103208;11225.71935701614;10767.730806729895;10963.83554246671;11157.776844463227;11255.718607034889;11440.771184576428;6705.430038446787;7771.590806849895;8010.998625171854;8034.718784842503;8195.016598008277;10499.68500944973;10902.563897444013;11077.390743509184;11104.777904442028;11187.608433704781 09:36:59 INFO - PROCESS | 1659 | |3;mootools.html;1479.8446163152864;1511.6447634805818;1592.0845513829552;1657.2956493490265;1751.2732216130314;6460.41856232939;7953.840923181548;8261.669533218665;8983.371164018521;8993.145651163137;7391.039164908568;8425.578721063954;8768.736937891857;8792.252658524034;8862.423942443736;1923.471045462502;2296.3111066680012;2331.207389487574;2337.672725818387;2339.099446713019;2295;2317.687112239843;2318.9072437102495;2339.3215967369442;2340.6137987232087;2322.0131444136173;2326.9069237230574;2327.3134425344565;2340.918067867625;2357.2456813819585;2297.7472478027403;2315.1202543033623;2315.710536182977;2325.1164557468223;2329.219711396684;1952.687569988795;2281.3726225288015;2296.908123675051;2309.307207837642;2320.7331156916835;8854.778630534289;8897.644094236222;8907.955460222722;8986.05646407131;8986.415882967585;5109.412417571953;5277.419483856773;5288.365396152448;5288.867778305535;5351.892962140774;3463.619001909813;3532.3994920863515;3651.525301710787;3652.141746689526;3687.649673281034;1036.4196050211906;1079.422508957704;1085.4572713643179;1090.231386872253;1099.763550836574;11577;11590.536378544932;11667.883321166764;11675.76648467026;11697.649070527897;4023.3964905264443;4345.152695224464;4595.126925884071;4688.2733176357715;4718.41019872516;8394.958025209957;8711.303095752324;9771.925088240288;9908.20734341251;9988.350757200758;5225.320708307896;5238.790448382022;5248.501392367719;5255.658382205144;5259.8422047338645;4336.306191009423;4385.978070109629;4749.073930583572;4773.164696178155;4814.350062741485;3195.3769015041844;3306.272620023591;3824.6366595173413;3900.980495097563;3927.8428862845167;1285.2288626823831;1293.3727142335958;1295.3264302562616;1303.094349427139;1309.312610879277;782.3154739602847;838.7441830241713;892.6518657723493;896.9103089691109;914.1041779056562;5445.482679145475;5479.726013699299;5547.944520554743;5839.532837373;5995.670238136944;988.8566157907208;998;1026.312370711625;1031.2368847053126;1047.6490375724218;3272.525483804835;3507.2459421224557;3805.0867791729784;3843.9621302248797;4007.7595344279434;10032.598696052222;10291;10551.84172237417;10576.735581610521;10585.300748309131;10329.84505232422;10465;10513.316634418738;10579.471026448648;10616.363018218932;8057.6374063167295;8215.671373145127;8218.178182181735;8230.300424463987;8256.628451719687;4961.975190124026;5676.20533125371;5785.161151633074;5835.79574714883;5850.151727999331;9697.951510242403;9953.452560109263;9972.252081093802;9974.301798874008;10194;7346.77959661211;7827.84344313122;8434.957825210835;10050.396976181453;10135.391876487434;6416.422521973045;7271.490995630255;7393.889091663522;7404.629768511445;7513.474056816189;6672.232693240339;7091.680874360563;8178.918210817816;8190.877136843072;8219.95890020546;6377.107204991397;6871.209810871814;7242.311980361857;7347.926520734938;7485.88771168422;6213.161223234986;6900.7929762107215;7206.639668016683;7226.458015648742;7299.05112335393;6873.621950792754;6879.449644028566;6881.174259088942;6953.860922781415;6970.233274339717;4941.258811178352;4950.6782059166035;4962.478939711278;4998.075356059141;5095.515925987025;4211.620954114144;4838.806447742191;4876.804927802848;4983.401991760867;5152.27868098474 09:36:59 INFO - PROCESS | 1659 | |4;prototype.html;266.93059804450843;277.8596808611651;305.19124320550554;312.2910992048051;315.82945209586825;4569.748663823489;5329.813456529022;5507.586930980177;5510.283663123799;5617.550595952319;5119.923201151976;5266.973665131679;5338.946610533893;5404.675719456835;5437.401885792559;3606;4085.8978525536877;4121.340585506319;4153.875383738493;4155.522114956789;3270.9182270443193;3489.476578513218;4104.897377565555;4120.217158739845;4141.81361838718;3550;3722.3671975764187;3965.790433917654;4108.239975604508;4119.6704263658985;3803.182315802088;4097.815598298083;4124;4126.773027483502;4158.958410415918;3430.622631510532;3549.4675798630155;3559.3059353426092;3568.750187486877;3578.6600272974288;4159.91680166395;4679.298105284201;5385.7576409061685;5400.432954539756;5411.404745477994;4713.9764301178275;4733.124371991193;4735.7158570485535;4749.905001899942;4813.614910807127;5064.848054558369;5327.3074500314715;5601.551875849963;5760.8271751847515;5840.8247752567495;3338.599368075822;3478.3043391321835;3731.6828069614116;3866.8592765134354;3898.9805050974846;4801.375821143264;6123.877522449526;6130.816075517741;6160.5687601867885;6181.814545563595;3325.6508066653137;3397.8470968806464;3442.8794992175153;3703.6111208322736;3780.8109594520165;3172.682731726855;3363.3105213431186;3489.581250249986;3887.1059656279213;4002.139539998856;3748.493953316322;4754.263089221176;4756.40660378777;4781.760911954389;4790.933802366489;5431.158170483582;5868.061110222344;5903.468687818117;5945.256842894638;5952.226210592682;1173.7769823733463;1348.3460521646994;1383.5434306678771;1387.3964825301027;1397.657573894382;699.9405050570657;811.8066830648321;825.9757900203704;834.4159088637978;859.4757198109148;4302.053548219386;4572.634189264851;4628.907421851612;4679.883002924954;4712.740799256006;4234;4513.480949690762;5088.643794934319;5101.505468753841;5123.154679477844;4168.353905144647;4503.752293623882;4530.478994915627;4655.205138707137;4679.619512243896;2535.6450096986437;2779.4719003389696;2822.971770282312;3117.4592706943768;3163.2724473371254;5304.283921670525;5360.0351936650995;6003.849903752441;6141.938580614226;6188.845278868064;5095.515925987025;5925.585209035326;6038.456538911519;6129.908051379233;6155.692215389213;5721.713914304268;6174.722137503823;6226.16328183187;6229;6297.937020629735;4530.844634618151;5249.685018898878;5266.420693723764;5540.97491963988;5952.73212705438;4500.437445319335;5344.839654810362;6009.849753756191;6022.337542870368;6031.60794548353;5190.455002224712;5709.34342550612;6078;6248.500119990299;6297.370262973666;3094.706002929718;3229.4832826747606;3844.96155038446;3845.807709614452;3996.0619099737187;5065.974670126626;5124.128898087409;5955.384576952896;6063.242094738158;6092.573519853568;5158.406783219978;5404.32445944257;5629.408912064338;5943.910841337297;5948.315943666534;5180.559652429435;5225.372955245395;5615.971920140373;5787.974209026807;5839.766409343579;4710.740909250024;5689.886202276014;5701.258836351248;5716.971415142898;5736.799212027559;4884.242942343943;5656.462636049569;5847.912281315868;5857.866041667688;5864.237649105589;5621.723202103155;5645.548356131418;5729.914051289151;5755.309362876482;5760.934227167987 09:36:59 INFO - PROCESS | 1659 | |5;yui.html;705.7036044861158;707.9787606371805;719.6186021408655;721.8231533274345;795.4193438789683;15114.848851511482;15745.370185192578;15813.209339533021;15870.04779713218;15878.206089695526;14093.083949543272;14264.358103885324;14274.857251427482;14275.857241427582;14290.92854535726;671.892497200448;703.8099713077492;820.8071103290722;825.1212458731462;846.4963346808639;856.6402111113316;857.5540718826206;861.2205953611959;861.6294993152942;863.6459051788768;877.3551439691821;899.9055099214586;900.6847603338812;901.7925877048283;904.7059705595675;889.7375274294099;894.4007514964957;897.5557099235855;898.7124120281512;899.7120921305185;655.4199533412916;667.1027468055457;667.2960027171332;668.1581207678312;669.0299066353806;14202;14520;14560.708785824225;14584.562463126123;14661.193634350106;14074.155550666992;14163.929180354135;14170;14186.71626567463;14330.785038224434;10147.985201479867;11058.723531911686;11608.668285226155;11748.647540573797;11893.167478276524;3191.2341038150907;3727.9440808387894;3731.440283957428;3773.52830896138;3806.6764325032404;105288;106171.93828061775;106778;107778;107863.3820492701;9190;9559.713208603753;10904.836427453594;10955.945220273848;11032.944835275934;19309.710354344697;22430;22555.09779608797;22580.322590322317;22662.320130396114;7720.420968427278;7836.294733474015;7867;7988.161243069394;8101.189881011852;3788.1666033472593;4316.6546676265825;4371.082072764755;4423.51341352451;4482.641388688962;3469.8959031229106;3562.2163124112653;3600.1719604491113;3619.4751760995036;3619.873304434332;568.6303902463432;644.2140588482044;649.1398896462207;651.6285717141184;660.455124522271;566.2101368590793;593.2347272019047;649.1982401733854;649.6037417175519;659.4065341192965;7459.403247740168;8357.331413486907;8839;8893.28853691703;8966.820663586692;543.4429709547745;574.130192757972;644.9226092868792;647.821848991531;686.8179932317939;3638.871949695603;4011.538673052636;4564.0871825635;4592.747398893026;4653.744044077541;156965;158813;163640.18179909023;164455.1777241106;164726;116981.8301816988;136963.31518342346;138851;139048.30475847557;139363;105077;114774;115817;119537;123562;8885.733427997171;8927.285817134743;8933;8944.88188976378;8983.730488085368;57415.31313291655;57491.137632934704;64856;65796.34203657902;66141.70866330779;73246;85649.57175214334;93615.53192233996;99505.50247249007;104479;11022.559097636005;11587.8261826071;11686.824697629372;11707.648770536898;12133.272003679807;12770.23378597287;12801.231926084465;12860.164089334163;13074.411651475897;13261.867381326063;11753.823692644446;12046.457909393923;12095.939520302341;12168.452419641315;12504.249745015328;11938.462769175581;12001.219920705125;12128.878711212776;12248.081393895316;12526;11899.524019039141;11981.28112313264;12052.698682533006;12090.395480225778;12241.938790305992;8475.83048339042;10135.64525241613;10189.337693049929;10200.88755051887;10231.437270949871;8532.786680333042;8967.417117887317;9121.72910204383;10218.897811021794;10483.37099774016 09:36:59 INFO - PROCESS | 1659 | __end_tp_report 09:36:59 INFO - PROCESS | 1659 | __start_cc_report 09:36:59 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,354 09:36:59 INFO - PROCESS | 1659 | __end_cc_report 09:36:59 INFO - PROCESS | 1659 | __startTimestamp1475858219705__endTimestamp 09:36:59 INFO - PROCESS | 1659 | 09:36:59 INFO - PROCESS | 1659 | ------- Summary: start ------- 09:36:59 INFO - PROCESS | 1659 | Number of tests: 6 09:36:59 INFO - PROCESS | 1659 | 09:36:59 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5699.28 Median:5983.82 stddev:701.38 (11.7%) stddev-sans-first:660.33 09:36:59 INFO - PROCESS | 1659 | Values: 2609.7 2845.5 2968.9 3369.3 3370.0 4670.5 5002.7 5232.3 5715.8 5722.5 5560.5 5621.0 5674.5 5693.7 5716.9 5102.5 5223.7 5266.4 5275.8 5293.6 5276.9 5280.7 5302.8 5317.8 5354.5 4512.8 5086.2 5280.7 5300.8 5301.7 5266.7 5292.5 5300.6 5301.5 5302.2 4391.7 4905.6 5138.2 5245.8 5261.8 5664.2 5765.9 5811.2 5850.4 5890.0 5566.3 5901.7 6189.0 6211.9 6224.0 6108.8 6143.8 6166.5 6167.6 6206.3 5914.6 5917.1 5943.5 5968.7 5972.3 6261.7 6264.7 6270.8 6286.4 6289.7 6020.4 6030.6 6120.0 6181.7 6193.6 6116.6 6131.4 6133.9 6140.5 6168.9 6198.7 6246.2 6253.8 6261.2 6511.1 6132.4 6144.3 6182.9 6221.9 6248.5 4946.5 4967.5 4974.7 4980.2 5020.8 3934.4 4437.1 4446.4 4447.1 4448.8 4646.2 5779.4 5810.9 5814.0 5856.1 6173.8 6201.8 6205.6 6211.1 6232.4 5920.9 5937.1 5953.0 5961.1 5993.0 5331.7 5356.8 5370.6 5382.0 5414.6 5276.8 6346.6 6351.4 6363.6 6368.7 6153.6 6187.1 6206.0 6213.6 6217.8 6133.6 6193.6 6226.2 6251.7 6301.7 6111.9 6189.9 6198.4 6206.1 6223.3 5154.7 5325.0 6058.6 6111.4 6192.7 5962.0 6109.4 6115.4 6156.6 6210.9 6126.7 6137.2 6156.4 6173.6 6195.4 5365.6 5975.3 6053.8 6064.5 6081.8 5983.8 6117.3 6130.5 6153.9 6172.0 6003.4 6013.3 6049.6 6083.1 6116.2 09:36:59 INFO - PROCESS | 1659 | 09:36:59 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:66322.10 Median:39013.52 stddev:144448.13 (370.3%) stddev-sans-first:144768.68 09:36:59 INFO - PROCESS | 1659 | Values: 449.4 512.5 531.7 533.8 546.2 30631.9 39683.6 40259.6 42306.8 42435.0 41572.6 41684.6 41988.0 41990.0 41993.4 9556.8 9655.9 9743.5 9759.0 9759.0 9552.1 9569.4 9632.8 9648.6 9677.3 9659.0 9684.7 9697.0 9700.3 9707.8 9615.6 9638.5 9669.7 9677.6 9708.1 9485.7 9546.5 9557.5 9560.0 9566.1 33216.0 38543.0 39377.0 39456.0 39698.4 3074.2 3361.9 3411.3 3498.6 3556.0 28516.7 30175.2 30582.0 30986.5 31379.0 3211.5 3220.7 3227.6 3317.2 3320.5 414586.0 416714.0 417555.0 419772.0 421373.0 59155.0 59227.7 59232.0 59579.1 59696.4 61658.7 61785.4 61880.7 62123.4 62332.7 11631.0 15828.7 15833.4 15903.8 16141.4 12361.2 12393.9 12435.2 12491.9 12496.9 16203.7 17525.0 18243.2 18283.6 18288.7 3206.2 7207.8 8038.1 8054.4 8075.0 869.7 1002.6 1020.7 1062.5 1073.4 27938.4 28968.4 29259.0 29865.4 30063.7 930.0 935.9 1096.5 1116.8 1128.9 12724.2 12738.9 14808.3 15134.2 15304.9 555006.0 586500.0 650762.0 654532.7 656521.0 55170.9 55788.9 55866.4 55874.3 56269.4 1068.5 1365.8 1378.4 1384.1 1390.1 22773.7 25015.9 27085.7 29299.0 30081.7 46197.0 46911.3 47300.5 47418.8 47428.0 438128.0 524338.0 603506.0 605166.9 609581.0 25553.2 25815.5 26220.7 26222.0 26246.3 44641.0 45651.0 45839.8 46143.8 47043.5 30556.5 31570.5 37585.8 37588.4 38650.0 27294.6 35173.1 37396.8 38172.8 38619.8 36567.9 36587.3 37437.4 37569.9 37831.9 31553.8 33571.8 33762.7 33957.5 34816.0 24824.4 24881.5 25115.0 25366.4 25405.7 09:36:59 INFO - PROCESS | 1659 | 09:36:59 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:33783.96 Median:18591.48 stddev:97832.76 (526.2%) stddev-sans-first:98074.98 09:36:59 INFO - PROCESS | 1659 | Values: 341.7 366.7 372.1 382.5 396.7 19641.3 20037.9 20855.0 20880.3 21084.9 11457.7 11811.9 11826.3 11850.6 13576.9 5114.5 5434.1 5439.6 5439.8 5468.1 3874.6 4073.3 5075.6 5452.0 6039.2 6066.0 6097.8 6114.3 6124.5 6131.1 5813.4 5941.5 6013.0 6060.0 6063.3 5495.6 5791.7 5797.0 5847.0 5874.7 13024.9 13065.7 13101.3 13101.3 16384.0 2128.4 2253.6 2327.1 2529.5 2717.8 8068.4 9884.0 10105.1 10319.2 13292.8 1850.1 1926.0 1929.2 1979.6 1979.8 107635.5 116760.0 116919.0 117558.2 117716.0 9098.7 11385.4 11388.3 11542.2 11645.9 14167.3 14328.9 14395.2 14408.6 14428.4 8128.9 8374.0 8418.5 8481.3 8515.4 7290.0 7843.4 8288.5 8290.3 8406.3 4480.5 4512.6 4515.1 4553.1 4630.6 1374.6 1410.4 1575.9 1618.5 2007.9 869.9 875.3 914.6 925.4 931.8 20017.6 20770.9 20878.7 20986.6 21100.1 867.8 888.3 902.7 940.5 1012.5 7456.8 9868.0 10619.9 10620.7 11073.0 551883.2 560328.0 597393.0 604303.0 609063.0 95459.0 124026.0 130938.3 132505.0 132758.0 947.1 952.1 982.2 983.4 985.9 16875.6 17285.7 18217.1 18583.9 18825.0 36816.3 43963.6 54438.0 55713.6 57755.7 83705.6 83873.0 84089.7 84504.0 85152.0 5858.9 6918.7 7093.1 7344.9 7412.6 8980.5 11993.5 12254.1 12485.2 12693.6 10538.5 10970.8 11117.8 11136.5 11194.0 10561.5 10790.7 11024.3 11029.9 11225.7 10767.7 10963.8 11157.8 11255.7 11440.8 6705.4 7771.6 8011.0 8034.7 8195.0 10499.7 10902.6 11077.4 11104.8 11187.6 09:36:59 INFO - PROCESS | 1659 | 09:36:59 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5529.02 Median:4956.33 stddev:3100.55 (62.6%) stddev-sans-first:3094.31 09:36:59 INFO - PROCESS | 1659 | Values: 1479.8 1511.6 1592.1 1657.3 1751.3 6460.4 7953.8 8261.7 8983.4 8993.1 7391.0 8425.6 8768.7 8792.3 8862.4 1923.5 2296.3 2331.2 2337.7 2339.1 2295.0 2317.7 2318.9 2339.3 2340.6 2322.0 2326.9 2327.3 2340.9 2357.2 2297.7 2315.1 2315.7 2325.1 2329.2 1952.7 2281.4 2296.9 2309.3 2320.7 8854.8 8897.6 8908.0 8986.1 8986.4 5109.4 5277.4 5288.4 5288.9 5351.9 3463.6 3532.4 3651.5 3652.1 3687.6 1036.4 1079.4 1085.5 1090.2 1099.8 11577.0 11590.5 11667.9 11675.8 11697.6 4023.4 4345.2 4595.1 4688.3 4718.4 8395.0 8711.3 9771.9 9908.2 9988.4 5225.3 5238.8 5248.5 5255.7 5259.8 4336.3 4386.0 4749.1 4773.2 4814.4 3195.4 3306.3 3824.6 3901.0 3927.8 1285.2 1293.4 1295.3 1303.1 1309.3 782.3 838.7 892.7 896.9 914.1 5445.5 5479.7 5547.9 5839.5 5995.7 988.9 998.0 1026.3 1031.2 1047.6 3272.5 3507.2 3805.1 3844.0 4007.8 10032.6 10291.0 10551.8 10576.7 10585.3 10329.8 10465.0 10513.3 10579.5 10616.4 8057.6 8215.7 8218.2 8230.3 8256.6 4962.0 5676.2 5785.2 5835.8 5850.2 9698.0 9953.5 9972.3 9974.3 10194.0 7346.8 7827.8 8435.0 10050.4 10135.4 6416.4 7271.5 7393.9 7404.6 7513.5 6672.2 7091.7 8178.9 8190.9 8220.0 6377.1 6871.2 7242.3 7347.9 7485.9 6213.2 6900.8 7206.6 7226.5 7299.1 6873.6 6879.4 6881.2 6953.9 6970.2 4941.3 4950.7 4962.5 4998.1 5095.5 4211.6 4838.8 4876.8 4983.4 5152.3 09:36:59 INFO - PROCESS | 1659 | 09:36:59 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4537.29 Median:5065.41 stddev:1464.33 (28.9%) stddev-sans-first:1432.93 09:36:59 INFO - PROCESS | 1659 | Values: 266.9 277.9 305.2 312.3 315.8 4569.7 5329.8 5507.6 5510.3 5617.6 5119.9 5267.0 5338.9 5404.7 5437.4 3606.0 4085.9 4121.3 4153.9 4155.5 3270.9 3489.5 4104.9 4120.2 4141.8 3550.0 3722.4 3965.8 4108.2 4119.7 3803.2 4097.8 4124.0 4126.8 4159.0 3430.6 3549.5 3559.3 3568.8 3578.7 4159.9 4679.3 5385.8 5400.4 5411.4 4714.0 4733.1 4735.7 4749.9 4813.6 5064.8 5327.3 5601.6 5760.8 5840.8 3338.6 3478.3 3731.7 3866.9 3899.0 4801.4 6123.9 6130.8 6160.6 6181.8 3325.7 3397.8 3442.9 3703.6 3780.8 3172.7 3363.3 3489.6 3887.1 4002.1 3748.5 4754.3 4756.4 4781.8 4790.9 5431.2 5868.1 5903.5 5945.3 5952.2 1173.8 1348.3 1383.5 1387.4 1397.7 699.9 811.8 826.0 834.4 859.5 4302.1 4572.6 4628.9 4679.9 4712.7 4234.0 4513.5 5088.6 5101.5 5123.2 4168.4 4503.8 4530.5 4655.2 4679.6 2535.6 2779.5 2823.0 3117.5 3163.3 5304.3 5360.0 6003.8 6141.9 6188.8 5095.5 5925.6 6038.5 6129.9 6155.7 5721.7 6174.7 6226.2 6229.0 6297.9 4530.8 5249.7 5266.4 5541.0 5952.7 4500.4 5344.8 6009.8 6022.3 6031.6 5190.5 5709.3 6078.0 6248.5 6297.4 3094.7 3229.5 3845.0 3845.8 3996.1 5066.0 5124.1 5955.4 6063.2 6092.6 5158.4 5404.3 5629.4 5943.9 5948.3 5180.6 5225.4 5616.0 5788.0 5839.8 4710.7 5689.9 5701.3 5717.0 5736.8 4884.2 5656.5 5847.9 5857.9 5864.2 5621.7 5645.5 5729.9 5755.3 5760.9 09:36:59 INFO - PROCESS | 1659 | 09:36:59 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:25064.73 Median:3629.37 stddev:41491.72 (1143.2%) stddev-sans-first:41567.81 09:36:59 INFO - PROCESS | 1659 | Values: 705.7 708.0 719.6 721.8 795.4 15114.8 15745.4 15813.2 15870.0 15878.2 14093.1 14264.4 14274.9 14275.9 14290.9 671.9 703.8 820.8 825.1 846.5 856.6 857.6 861.2 861.6 863.6 877.4 899.9 900.7 901.8 904.7 889.7 894.4 897.6 898.7 899.7 655.4 667.1 667.3 668.2 669.0 14202.0 14520.0 14560.7 14584.6 14661.2 14074.2 14163.9 14170.0 14186.7 14330.8 10148.0 11058.7 11608.7 11748.6 11893.2 3191.2 3727.9 3731.4 3773.5 3806.7 105288.0 106171.9 106778.0 107778.0 107863.4 9190.0 9559.7 10904.8 10955.9 11032.9 19309.7 22430.0 22555.1 22580.3 22662.3 7720.4 7836.3 7867.0 7988.2 8101.2 3788.2 4316.7 4371.1 4423.5 4482.6 3469.9 3562.2 3600.2 3619.5 3619.9 568.6 644.2 649.1 651.6 660.5 566.2 593.2 649.2 649.6 659.4 7459.4 8357.3 8839.0 8893.3 8966.8 543.4 574.1 644.9 647.8 686.8 3638.9 4011.5 4564.1 4592.7 4653.7 156965.0 158813.0 163640.2 164455.2 164726.0 116981.8 136963.3 138851.0 139048.3 139363.0 105077.0 114774.0 115817.0 119537.0 123562.0 8885.7 8927.3 8933.0 8944.9 8983.7 57415.3 57491.1 64856.0 65796.3 66141.7 73246.0 85649.6 93615.5 99505.5 104479.0 11022.6 11587.8 11686.8 11707.6 12133.3 12770.2 12801.2 12860.2 13074.4 13261.9 11753.8 12046.5 12095.9 12168.5 12504.2 11938.5 12001.2 12128.9 12248.1 12526.0 11899.5 11981.3 12052.7 12090.4 12241.9 8475.8 10135.6 10189.3 10200.9 10231.4 8532.8 8967.4 9121.7 10218.9 10483.4 09:36:59 INFO - PROCESS | 1659 | -------- Summary: end -------- 09:36:59 INFO - PROCESS | 1659 | 09:37:00 INFO - TEST-INFO | 1659: exit 0 09:37:00 INFO - TEST-OK | dromaeo_css | took 1115389ms 09:37:00 INFO - TEST-START | kraken 09:37:00 INFO - operating with platform_type : mac_ 09:37:00 INFO - Initialising browser for kraken test... 09:37:00 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpb4AviL/profile http://localhost:49280/getInfo.html) 09:37:03 INFO - PROCESS | 1668 | 2016-10-07 09:37:03.195 plugin-container[1669:9916] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a17, name = 'com.apple.tsm.portname' 09:37:03 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:37:03 INFO - PROCESS | 1668 | 2016-10-07 09:37:03.197 plugin-container[1669:9916] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9107, name = 'com.apple.CFPasteboardClient' 09:37:03 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:37:03 INFO - PROCESS | 1668 | 2016-10-07 09:37:03.197 plugin-container[1669:9916] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:37:03 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 09:37:03 INFO - PROCESS | 1668 | colorDepth:24 09:37:03 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 09:37:03 INFO - PROCESS | 1668 | __metrics 09:37:03 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 09:37:04 INFO - TEST-INFO | 1668: exit 0 09:37:04 INFO - Browser initialized. 09:37:04 INFO - Running cycle 1/1 for kraken test... 09:37:04 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpb4AviL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:06 INFO - PROCESS | 1670 | 2016-10-07 09:37:06.412 plugin-container[1671:10036] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8923, name = 'com.apple.tsm.portname' 09:37:06 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:37:06 INFO - PROCESS | 1670 | 2016-10-07 09:37:06.414 plugin-container[1671:10036] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 09:37:06 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:37:06 INFO - PROCESS | 1670 | 2016-10-07 09:37:06.414 plugin-container[1671:10036] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:37:16 INFO - PROCESS | 1670 | RSS: Main: 240074752 09:37:16 INFO - PROCESS | 1670 | 09:37:19 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 09:37:20 INFO - PROCESS | 1670 | RSS: Main: 253816832 09:37:20 INFO - PROCESS | 1670 | 09:37:22 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 09:37:23 INFO - PROCESS | 1670 | RSS: Main: 261431296 09:37:23 INFO - PROCESS | 1670 | 09:37:26 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 09:37:27 INFO - PROCESS | 1670 | RSS: Main: 262295552 09:37:27 INFO - PROCESS | 1670 | 09:37:29 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 09:37:30 INFO - PROCESS | 1670 | RSS: Main: 263434240 09:37:30 INFO - PROCESS | 1670 | 09:37:32 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:37:32 INFO - PROCESS | 1670 | RSS: Main: 264998912 09:37:32 INFO - PROCESS | 1670 | 09:37:35 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 09:37:36 INFO - PROCESS | 1670 | RSS: Main: 265850880 09:37:36 INFO - PROCESS | 1670 | 09:37:39 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 09:37:39 INFO - PROCESS | 1670 | RSS: Main: 266092544 09:37:39 INFO - PROCESS | 1670 | 09:37:42 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 09:37:43 INFO - PROCESS | 1670 | RSS: Main: 266145792 09:37:43 INFO - PROCESS | 1670 | 09:37:45 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:37:45 INFO - PROCESS | 1670 | RSS: Main: 265986048 09:37:45 INFO - PROCESS | 1670 | 09:37:47 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:37:48 INFO - PROCESS | 1670 | RSS: Main: 266018816 09:37:48 INFO - PROCESS | 1670 | 09:37:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:37:51 INFO - PROCESS | 1670 | RSS: Main: 266096640 09:37:51 INFO - PROCESS | 1670 | 09:37:54 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:37:54 INFO - PROCESS | 1670 | RSS: Main: 266444800 09:37:54 INFO - PROCESS | 1670 | 09:37:57 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:37:58 INFO - PROCESS | 1670 | RSS: Main: 266461184 09:37:58 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 09:38:00 INFO - PROCESS | 1670 | RSS: Main: 267829248 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | __start_tp_report 09:38:00 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 09:38:00 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 09:38:00 INFO - PROCESS | 1670 | |i|pagename|runs| 09:38:00 INFO - PROCESS | 1670 | |0;ai-astar;115;118;117;116;117;125;167;120;119;118 09:38:00 INFO - PROCESS | 1670 | |1;audio-beat-detection;151;114;131;193;105;145;149;149;142;117 09:38:00 INFO - PROCESS | 1670 | |2;audio-dft;197;275;255;194;196;199;199;194;194;195 09:38:00 INFO - PROCESS | 1670 | |3;audio-fft;82;59;58;84;60;60;59;62;81;66 09:38:00 INFO - PROCESS | 1670 | |4;audio-oscillator;89;71;86;75;68;69;79;99;105;122 09:38:00 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;88;90;88;76;136;127;89;88;90;85 09:38:00 INFO - PROCESS | 1670 | |6;imaging-darkroom;152;126;96;141;97;100;107;90;108;127 09:38:00 INFO - PROCESS | 1670 | |7;imaging-desaturate;98;92;92;129;87;79;144;76;104;108 09:38:00 INFO - PROCESS | 1670 | |8;json-parse-financial;59;56;55;55;58;58;56;55;57;56 09:38:00 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;70;77;72;104;79;76;70;69;71;71 09:38:00 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;84;103;75;112;109;112;95;89;87;90 09:38:00 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;147;146;146;145;145;150;146;152;198;145 09:38:00 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;173;178;174;171;182;213;235;203;172;172 09:38:00 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;70;69;69;69;73;73;67;66;71;69 09:38:00 INFO - PROCESS | 1670 | __end_tp_report 09:38:00 INFO - PROCESS | 1670 | __start_cc_report 09:38:00 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,928 09:38:00 INFO - PROCESS | 1670 | __end_cc_report 09:38:00 INFO - PROCESS | 1670 | __startTimestamp1475858280415__endTimestamp 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | ------- Summary: start ------- 09:38:00 INFO - PROCESS | 1670 | Number of tests: 14 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:123.20 Median:118.50 stddev:15.63 (13.2%) stddev-sans-first:16.30 09:38:00 INFO - PROCESS | 1670 | Values: 115.0 118.0 117.0 116.0 117.0 125.0 167.0 120.0 119.0 118.0 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:139.60 Median:147.00 stddev:25.01 (17.0%) stddev-sans-first:26.19 09:38:00 INFO - PROCESS | 1670 | Values: 151.0 114.0 131.0 193.0 105.0 145.0 149.0 149.0 142.0 117.0 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:209.80 Median:198.00 stddev:29.53 (14.9%) stddev-sans-first:30.96 09:38:00 INFO - PROCESS | 1670 | Values: 197.0 275.0 255.0 194.0 196.0 199.0 199.0 194.0 194.0 195.0 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:67.10 Median:64.00 stddev:10.76 (16.8%) stddev-sans-first:9.98 09:38:00 INFO - PROCESS | 1670 | Values: 82.0 59.0 58.0 84.0 60.0 60.0 59.0 62.0 81.0 66.0 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:86.30 Median:77.00 stddev:17.77 (23.1%) stddev-sans-first:18.82 09:38:00 INFO - PROCESS | 1670 | Values: 89.0 71.0 86.0 75.0 68.0 69.0 79.0 99.0 105.0 122.0 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:88.00 stddev:19.42 (22.1%) stddev-sans-first:20.40 09:38:00 INFO - PROCESS | 1670 | Values: 88.0 90.0 88.0 76.0 136.0 127.0 89.0 88.0 90.0 85.0 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:114.40 Median:146.50 stddev:20.96 (14.3%) stddev-sans-first:17.26 09:38:00 INFO - PROCESS | 1670 | Values: 152.0 126.0 96.0 141.0 97.0 100.0 107.0 90.0 108.0 127.0 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:100.90 Median:83.00 stddev:21.52 (25.9%) stddev-sans-first:22.80 09:38:00 INFO - PROCESS | 1670 | Values: 98.0 92.0 92.0 129.0 87.0 79.0 144.0 76.0 104.0 108.0 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:56.50 stddev:1.43 (2.5%) stddev-sans-first:1.20 09:38:00 INFO - PROCESS | 1670 | Values: 59.0 56.0 55.0 55.0 58.0 58.0 56.0 55.0 57.0 56.0 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:75.90 Median:71.50 stddev:10.44 (14.6%) stddev-sans-first:10.85 09:38:00 INFO - PROCESS | 1670 | Values: 70.0 77.0 72.0 104.0 79.0 76.0 70.0 69.0 71.0 71.0 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:95.60 Median:85.50 stddev:12.83 (15.0%) stddev-sans-first:12.90 09:38:00 INFO - PROCESS | 1670 | Values: 84.0 103.0 75.0 112.0 109.0 112.0 95.0 89.0 87.0 90.0 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:152.00 Median:146.50 stddev:16.33 (11.1%) stddev-sans-first:17.22 09:38:00 INFO - PROCESS | 1670 | Values: 147.0 146.0 146.0 145.0 145.0 150.0 146.0 152.0 198.0 145.0 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.30 Median:180.00 stddev:22.14 (12.3%) stddev-sans-first:22.87 09:38:00 INFO - PROCESS | 1670 | Values: 173.0 178.0 174.0 171.0 182.0 213.0 235.0 203.0 172.0 172.0 09:38:00 INFO - PROCESS | 1670 | 09:38:00 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:69.50 stddev:2.27 (3.3%) stddev-sans-first:2.40 09:38:00 INFO - PROCESS | 1670 | Values: 70.0 69.0 69.0 69.0 73.0 73.0 67.0 66.0 71.0 69.0 09:38:00 INFO - PROCESS | 1670 | -------- Summary: end -------- 09:38:00 INFO - PROCESS | 1670 | 09:38:01 INFO - TEST-INFO | 1670: exit 0 09:38:01 INFO - TEST-OK | kraken | took 60773ms 09:38:01 INFO - SUITE-END | took 1176s 09:38:01 INFO - Completed test suite (00:19:36) 09:38:01 INFO - javascript benchmark 09:38:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7166.009496596561, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2609.6737907761535, 2845.5020371435, 2968.896088636898, 3369.326134773046, 3370.0226934189072, 4670.532946705336, 5002.6992981824715, 5232.3197984262, 5715.799947001856, 5722.513586345161, 5560.499555040045, 5621.0, 5674.489295963363, 5693.715314234282, 5716.8856622867515, 5102.464241254671, 5223.738813059351, 5266.420693723688, 5275.762590683428, 5293.550048245904, 5276.92084618731, 5280.683159010452, 5302.840914772543, 5317.7607007684555, 5354.518093371596, 4512.7743612819395, 5086.211637196222, 5280.683159010452, 5300.840974770763, 5301.7084060376665, 5266.657667251617, 5292.52367286946, 5300.575953923677, 5301.469853014687, 5302.1781623848365, 4391.670624703128, 4905.583025442842, 5138.229265610151, 5245.763940622643, 5261.842144735664, 5664.150377443375, 5765.942340576583, 5811.244538210048, 5850.444207800252, 5889.998700220973, 5566.332040155166, 5901.675407852566, 6188.978818494943, 6211.875762484725, 6223.968880155616, 6108.797423689706, 6143.849652621591, 6166.506679465632, 6167.599106058136, 6206.2862770781485, 5914.615549989237, 5917.14201440793, 5943.494802941798, 5968.67172305523, 5972.283326000865, 6261.655608941552, 6264.718087686065, 6270.811875643647, 6286.4342209201395, 6289.654069026247, 6020.397960204032, 6030.608010479393, 6120.0, 6181.721822517998, 6193.566450348432, 6116.633002019893, 6131.420293029677, 6133.877322453526, 6140.473573783541, 6168.907466388007, 6198.659073750897, 6246.187995560639, 6253.812385628438, 6261.248650161919, 6511.0558968949235, 6132.355508992545, 6144.293406258337, 6182.938170618237, 6221.875562488815, 6248.5313601480075, 4946.455889852114, 4967.5032496750755, 4974.651774375831, 4980.203167493184, 5020.824271150492, 3934.390169523728, 4437.134758722083, 4446.444194475691, 4447.110577884436, 4448.822047118079, 4646.233371493666, 5779.422057794187, 5810.912836307459, 5814.04068328721, 5856.092305692625, 6173.753049878044, 6201.7519299228425, 6205.596636218631, 6211.130441738161, 6232.439080482778, 5920.881582368328, 5937.050071988461, 5952.970235148797, 5961.076033214858, 5993.0, 5331.733413329318, 5356.839294821161, 5370.597205209625, 5381.973090134525, 5414.648047876875, 5276.81531146408, 6346.619202847844, 6351.428371446592, 6363.586366886323, 6368.745250189941, 6153.61588544922, 6187.102870083902, 6206.007038873759, 6213.627182369072, 6217.84969780592, 6133.610107582747, 6193.628382297076, 6226.1905952225925, 6251.656158911122, 6301.747930082745, 6111.895622426936, 6189.907151392643, 6198.380161983766, 6206.131141640084, 6223.253209614875, 5154.664946778447, 5325.0148722486465, 6058.636481811105, 6111.388861114031, 6192.690365481798, 5961.970190149022, 6109.389061093855, 6115.3578874217565, 6156.630602163885, 6210.937890621036, 6126.66303353302, 6137.232845894263, 6156.353582873734, 6173.59871608344, 6195.4424101831055, 5365.570754339566, 5975.312839023568, 6053.842934858572, 6064.545159112996, 6081.756729730761, 5983.820485385445, 6117.296510901126, 6130.47890929276, 6153.907691384722, 6172.043333283348, 6003.3996600339615, 6013.338532761305, 6049.637021778707, 6083.117947897442, 6116.235470566179], "value": 5649.902501861575, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.35293177823934, 512.5259135299848, 531.7212104887744, 533.847853361792, 546.1589152704835, 30631.855770096074, 39683.60316396835, 40259.59740402592, 42306.788466057704, 42435.0, 41572.58427415725, 41684.583154168526, 41988.0, 41989.95025124373, 41993.37009944853, 9556.761080972961, 9655.903440965605, 9743.464109473976, 9758.951205243999, 9759.02409759022, 9552.140307372336, 9569.377990430634, 9632.80734385312, 9648.565814538328, 9677.27420443466, 9658.985806490322, 9684.70945871625, 9696.981816909229, 9700.320977531575, 9707.805843883118, 9615.56729947154, 9638.469884156368, 9669.661561845336, 9677.564509597085, 9708.07773261546, 9485.715428537154, 9546.474943878084, 9557.474338911357, 9560.043995600454, 9566.13904748576, 33216.0035198942, 38543.0, 39377.0, 39456.0, 39698.404523932164, 3074.1546074829375, 3361.932761344784, 3411.3347897160065, 3498.6151523332414, 3555.982220088883, 28516.71483285182, 30175.245618859484, 30582.0, 30986.535201971987, 31379.0, 3211.5022171563446, 3220.6940340667597, 3227.6126864776143, 3317.1541256979317, 3320.5019247112887, 414585.999999997, 416714.0, 417555.0, 419772.0, 421373.0, 59155.0, 59227.70386148042, 59232.0, 59579.10631340533, 59696.40303596995, 61658.69170654118, 61785.382146177966, 61880.69059654673, 62123.37876621266, 62332.68833655803, 11631.011364033982, 15828.683426331409, 15833.366665333258, 15903.761443578356, 16141.435049773201, 12361.196522226026, 12393.946514546184, 12435.191712538655, 12491.938185254174, 12496.875031249752, 16203.703703703675, 17525.036123013357, 18243.17905694247, 18283.63432731338, 18288.725669114974, 3206.198450387403, 7207.819804504929, 8038.132163520662, 8054.436189466681, 8075.030996280484, 869.6564856881496, 1002.6340385759146, 1020.7142000239945, 1062.516554967488, 1073.393532654048, 27938.441231175264, 28968.420631587673, 29259.0, 29865.402691946474, 30063.699363006526, 929.9814003719888, 935.8923723771717, 1096.5230124895695, 1116.8045592021526, 1128.8814674459227, 12724.1729287596, 12738.936305318599, 14808.3336249869, 15134.243287835565, 15304.923475382551, 555006.0, 586500.0, 650762.0, 654532.7273363698, 656521.0, 55170.89658206813, 55788.884222315326, 55866.44133558694, 55874.32377028696, 56269.43730562724, 1068.460427484131, 1365.8497565267812, 1378.3659516622265, 1384.0657556149558, 1390.1103293891917, 22773.658395124417, 25015.87492062528, 27085.729142709108, 29299.0, 30081.69918300789, 46197.0, 46911.29633055439, 47300.52699472961, 47418.76290618525, 47428.0, 438128.0, 524338.0, 603506.0, 605166.9483305287, 609581.0, 25553.23340299794, 25815.483690326462, 26220.73779262183, 26222.0, 26246.34384140412, 44641.0, 45651.0, 45839.76225795398, 46143.769281154724, 47043.52956470392, 30556.541651875683, 31570.526442103845, 37585.81207093947, 37588.436173457965, 38650.03374915649, 27294.590581141696, 35173.12067198341, 37396.81301593475, 38172.80913595414, 38619.80690096531, 36567.902962911154, 36587.268254634226, 37437.43843842399, 37569.87290381293, 37831.86504404762, 31553.842230788698, 33571.83214083914, 33762.662373375955, 33957.49063764095, 34815.95552133339, 24824.37939051466, 24881.502369952137, 25115.0, 25366.365840854127, 25405.74594254034], "value": 19336.046026563756, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.6651681352275, 366.69747458346853, 372.07353689313607, 382.5409508589693, 396.65689178860293, 19641.312554060594, 20037.897915614652, 20854.99999999998, 20880.269190578332, 21084.894575527138, 11457.713557161074, 11811.940940295286, 11826.349550774705, 11850.585229516968, 13576.932115339434, 5114.539691427771, 5434.103372943479, 5439.646422982513, 5439.755211015514, 5468.070428027245, 3874.6319099685484, 4073.30753771858, 5075.6447048706605, 5451.972740136293, 6039.245094363205, 6065.969670151643, 6097.8170654880205, 6114.327423983358, 6124.540659450537, 6131.110988906606, 5813.389594092623, 5941.465268125889, 6012.96993515034, 6059.969700151515, 6063.302720187146, 5495.560355171577, 5791.710414479259, 5796.97101514494, 5847.0352391855295, 5874.676892770896, 13024.86975130246, 13065.673358166121, 13101.279429631366, 13101.279429631366, 16384.0, 2128.4146859613575, 2253.571821353954, 2327.0924339507606, 2529.4733239759403, 2717.8097533172686, 8068.3948703847445, 9884.0, 10105.141063009652, 10319.226058045677, 13292.800607990888, 1850.1119462658, 1926.0, 1929.1511734836627, 1979.6238714644317, 1979.8218160365636, 107635.46182269117, 116760.0, 116919.0, 117558.23662645153, 117716.0, 9098.727038188865, 11385.430728463543, 11388.259763115371, 11542.19204655683, 11645.941770291094, 14167.291635418187, 14328.85671143296, 14395.208263545606, 14408.567742967727, 14428.350724217435, 8128.878066829002, 8374.036985746718, 8418.536980466133, 8481.279091277187, 8515.40392172542, 7289.963550182321, 7843.411744119214, 8288.50268983863, 8290.253877151084, 8406.327493800482, 4480.529544397857, 4512.646206138211, 4515.074409745994, 4553.123057646599, 4630.606398456169, 1374.6357215337944, 1410.4358256697403, 1575.9290831912592, 1618.5387164658016, 2007.9397618071482, 869.852125138728, 875.3259989807813, 914.5884352041608, 925.3846192282057, 931.7810314576069, 20017.59964800696, 20770.896145519175, 20878.686819697716, 20986.580268394548, 21100.05049772764, 867.7591044805988, 888.3337496877342, 902.6576035188675, 940.5297351324338, 1012.4937531234383, 7456.813579660552, 9867.950660246797, 10619.884912084275, 10620.681379558626, 11073.003429691367, 551883.2405837945, 560328.0, 597393.0, 604303.0, 609063.0, 95459.0, 124026.0, 130938.34530827476, 132505.0, 132758.0, 947.1428357336621, 952.1335584618103, 982.1700662939804, 983.3902980152351, 985.8718366612294, 16875.578110547336, 17285.65428691394, 18217.089145542934, 18583.854158860486, 18825.0, 36816.26367472689, 43963.56036439595, 54438.0, 55713.60715982133, 57755.71122144531, 83705.58147209225, 83873.0, 84089.73865392146, 84504.0, 85152.0, 5858.882822343615, 6918.654067296515, 7093.113360829896, 7344.853102937805, 7412.555246685216, 8980.506072166094, 11993.460294286604, 12254.142210045211, 12485.188462749893, 12693.555725549562, 10538.473076346305, 10970.780584388109, 11117.829000294927, 11136.49885755159, 11194.0, 10561.471926403496, 10790.67627971162, 11024.283421577571, 11029.889701103208, 11225.71935701614, 10767.730806729895, 10963.83554246671, 11157.776844463227, 11255.718607034889, 11440.771184576428, 6705.430038446787, 7771.590806849895, 8010.998625171854, 8034.718784842503, 8195.016598008277, 10499.68500944973, 10902.563897444013, 11077.390743509184, 11104.777904442028, 11187.608433704781], "value": 9024.288329913677, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1479.8446163152864, 1511.6447634805818, 1592.0845513829552, 1657.2956493490265, 1751.2732216130314, 6460.41856232939, 7953.840923181548, 8261.669533218665, 8983.371164018521, 8993.145651163137, 7391.039164908568, 8425.578721063954, 8768.736937891857, 8792.252658524034, 8862.423942443736, 1923.471045462502, 2296.3111066680012, 2331.207389487574, 2337.672725818387, 2339.099446713019, 2295.0, 2317.687112239843, 2318.9072437102495, 2339.3215967369442, 2340.6137987232087, 2322.0131444136173, 2326.9069237230574, 2327.3134425344565, 2340.918067867625, 2357.2456813819585, 2297.7472478027403, 2315.1202543033623, 2315.710536182977, 2325.1164557468223, 2329.219711396684, 1952.687569988795, 2281.3726225288015, 2296.908123675051, 2309.307207837642, 2320.7331156916835, 8854.778630534289, 8897.644094236222, 8907.955460222722, 8986.05646407131, 8986.415882967585, 5109.412417571953, 5277.419483856773, 5288.365396152448, 5288.867778305535, 5351.892962140774, 3463.619001909813, 3532.3994920863515, 3651.525301710787, 3652.141746689526, 3687.649673281034, 1036.4196050211906, 1079.422508957704, 1085.4572713643179, 1090.231386872253, 1099.763550836574, 11577.0, 11590.536378544932, 11667.883321166764, 11675.76648467026, 11697.649070527897, 4023.3964905264443, 4345.152695224464, 4595.126925884071, 4688.2733176357715, 4718.41019872516, 8394.958025209957, 8711.303095752324, 9771.925088240288, 9908.20734341251, 9988.350757200758, 5225.320708307896, 5238.790448382022, 5248.501392367719, 5255.658382205144, 5259.8422047338645, 4336.306191009423, 4385.978070109629, 4749.073930583572, 4773.164696178155, 4814.350062741485, 3195.3769015041844, 3306.272620023591, 3824.6366595173413, 3900.980495097563, 3927.8428862845167, 1285.2288626823831, 1293.3727142335958, 1295.3264302562616, 1303.094349427139, 1309.312610879277, 782.3154739602847, 838.7441830241713, 892.6518657723493, 896.9103089691109, 914.1041779056562, 5445.482679145475, 5479.726013699299, 5547.944520554743, 5839.532837373, 5995.670238136944, 988.8566157907208, 998.0, 1026.312370711625, 1031.2368847053126, 1047.6490375724218, 3272.525483804835, 3507.2459421224557, 3805.0867791729784, 3843.9621302248797, 4007.7595344279434, 10032.598696052222, 10291.0, 10551.84172237417, 10576.735581610521, 10585.300748309131, 10329.84505232422, 10465.0, 10513.316634418738, 10579.471026448648, 10616.363018218932, 8057.6374063167295, 8215.671373145127, 8218.178182181735, 8230.300424463987, 8256.628451719687, 4961.975190124026, 5676.20533125371, 5785.161151633074, 5835.79574714883, 5850.151727999331, 9697.951510242403, 9953.452560109263, 9972.252081093802, 9974.301798874008, 10194.0, 7346.77959661211, 7827.84344313122, 8434.957825210835, 10050.396976181453, 10135.391876487434, 6416.422521973045, 7271.490995630255, 7393.889091663522, 7404.629768511445, 7513.474056816189, 6672.232693240339, 7091.680874360563, 8178.918210817816, 8190.877136843072, 8219.95890020546, 6377.107204991397, 6871.209810871814, 7242.311980361857, 7347.926520734938, 7485.88771168422, 6213.161223234986, 6900.7929762107215, 7206.639668016683, 7226.458015648742, 7299.05112335393, 6873.621950792754, 6879.449644028566, 6881.174259088942, 6953.860922781415, 6970.233274339717, 4941.258811178352, 4950.6782059166035, 4962.478939711278, 4998.075356059141, 5095.515925987025, 4211.620954114144, 4838.806447742191, 4876.804927802848, 4983.401991760867, 5152.27868098474], "value": 4469.044386199535, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.93059804450843, 277.8596808611651, 305.19124320550554, 312.2910992048051, 315.82945209586825, 4569.748663823489, 5329.813456529022, 5507.586930980177, 5510.283663123799, 5617.550595952319, 5119.923201151976, 5266.973665131679, 5338.946610533893, 5404.675719456835, 5437.401885792559, 3606.0, 4085.8978525536877, 4121.340585506319, 4153.875383738493, 4155.522114956789, 3270.9182270443193, 3489.476578513218, 4104.897377565555, 4120.217158739845, 4141.81361838718, 3550.0, 3722.3671975764187, 3965.790433917654, 4108.239975604508, 4119.6704263658985, 3803.182315802088, 4097.815598298083, 4124.0, 4126.773027483502, 4158.958410415918, 3430.622631510532, 3549.4675798630155, 3559.3059353426092, 3568.750187486877, 3578.6600272974288, 4159.91680166395, 4679.298105284201, 5385.7576409061685, 5400.432954539756, 5411.404745477994, 4713.9764301178275, 4733.124371991193, 4735.7158570485535, 4749.905001899942, 4813.614910807127, 5064.848054558369, 5327.3074500314715, 5601.551875849963, 5760.8271751847515, 5840.8247752567495, 3338.599368075822, 3478.3043391321835, 3731.6828069614116, 3866.8592765134354, 3898.9805050974846, 4801.375821143264, 6123.877522449526, 6130.816075517741, 6160.5687601867885, 6181.814545563595, 3325.6508066653137, 3397.8470968806464, 3442.8794992175153, 3703.6111208322736, 3780.8109594520165, 3172.682731726855, 3363.3105213431186, 3489.581250249986, 3887.1059656279213, 4002.139539998856, 3748.493953316322, 4754.263089221176, 4756.40660378777, 4781.760911954389, 4790.933802366489, 5431.158170483582, 5868.061110222344, 5903.468687818117, 5945.256842894638, 5952.226210592682, 1173.7769823733463, 1348.3460521646994, 1383.5434306678771, 1387.3964825301027, 1397.657573894382, 699.9405050570657, 811.8066830648321, 825.9757900203704, 834.4159088637978, 859.4757198109148, 4302.053548219386, 4572.634189264851, 4628.907421851612, 4679.883002924954, 4712.740799256006, 4234.0, 4513.480949690762, 5088.643794934319, 5101.505468753841, 5123.154679477844, 4168.353905144647, 4503.752293623882, 4530.478994915627, 4655.205138707137, 4679.619512243896, 2535.6450096986437, 2779.4719003389696, 2822.971770282312, 3117.4592706943768, 3163.2724473371254, 5304.283921670525, 5360.0351936650995, 6003.849903752441, 6141.938580614226, 6188.845278868064, 5095.515925987025, 5925.585209035326, 6038.456538911519, 6129.908051379233, 6155.692215389213, 5721.713914304268, 6174.722137503823, 6226.16328183187, 6229.0, 6297.937020629735, 4530.844634618151, 5249.685018898878, 5266.420693723764, 5540.97491963988, 5952.73212705438, 4500.437445319335, 5344.839654810362, 6009.849753756191, 6022.337542870368, 6031.60794548353, 5190.455002224712, 5709.34342550612, 6078.0, 6248.500119990299, 6297.370262973666, 3094.706002929718, 3229.4832826747606, 3844.96155038446, 3845.807709614452, 3996.0619099737187, 5065.974670126626, 5124.128898087409, 5955.384576952896, 6063.242094738158, 6092.573519853568, 5158.406783219978, 5404.32445944257, 5629.408912064338, 5943.910841337297, 5948.315943666534, 5180.559652429435, 5225.372955245395, 5615.971920140373, 5787.974209026807, 5839.766409343579, 4710.740909250024, 5689.886202276014, 5701.258836351248, 5716.971415142898, 5736.799212027559, 4884.242942343943, 5656.462636049569, 5847.912281315868, 5857.866041667688, 5864.237649105589, 5621.723202103155, 5645.548356131418, 5729.914051289151, 5755.309362876482, 5760.934227167987], "value": 4067.736727571386, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.7036044861158, 707.9787606371805, 719.6186021408655, 721.8231533274345, 795.4193438789683, 15114.848851511482, 15745.370185192578, 15813.209339533021, 15870.04779713218, 15878.206089695526, 14093.083949543272, 14264.358103885324, 14274.857251427482, 14275.857241427582, 14290.92854535726, 671.892497200448, 703.8099713077492, 820.8071103290722, 825.1212458731462, 846.4963346808639, 856.6402111113316, 857.5540718826206, 861.2205953611959, 861.6294993152942, 863.6459051788768, 877.3551439691821, 899.9055099214586, 900.6847603338812, 901.7925877048283, 904.7059705595675, 889.7375274294099, 894.4007514964957, 897.5557099235855, 898.7124120281512, 899.7120921305185, 655.4199533412916, 667.1027468055457, 667.2960027171332, 668.1581207678312, 669.0299066353806, 14202.0, 14520.0, 14560.708785824225, 14584.562463126123, 14661.193634350106, 14074.155550666992, 14163.929180354135, 14170.0, 14186.71626567463, 14330.785038224434, 10147.985201479867, 11058.723531911686, 11608.668285226155, 11748.647540573797, 11893.167478276524, 3191.2341038150907, 3727.9440808387894, 3731.440283957428, 3773.52830896138, 3806.6764325032404, 105288.0, 106171.93828061775, 106778.0, 107778.0, 107863.3820492701, 9190.0, 9559.713208603753, 10904.836427453594, 10955.945220273848, 11032.944835275934, 19309.710354344697, 22430.0, 22555.09779608797, 22580.322590322317, 22662.320130396114, 7720.420968427278, 7836.294733474015, 7867.0, 7988.161243069394, 8101.189881011852, 3788.1666033472593, 4316.6546676265825, 4371.082072764755, 4423.51341352451, 4482.641388688962, 3469.8959031229106, 3562.2163124112653, 3600.1719604491113, 3619.4751760995036, 3619.873304434332, 568.6303902463432, 644.2140588482044, 649.1398896462207, 651.6285717141184, 660.455124522271, 566.2101368590793, 593.2347272019047, 649.1982401733854, 649.6037417175519, 659.4065341192965, 7459.403247740168, 8357.331413486907, 8839.0, 8893.28853691703, 8966.820663586692, 543.4429709547745, 574.130192757972, 644.9226092868792, 647.821848991531, 686.8179932317939, 3638.871949695603, 4011.538673052636, 4564.0871825635, 4592.747398893026, 4653.744044077541, 156965.0, 158813.0, 163640.18179909023, 164455.1777241106, 164726.0, 116981.8301816988, 136963.31518342346, 138851.0, 139048.30475847557, 139363.0, 105077.0, 114774.0, 115817.0, 119537.0, 123562.0, 8885.733427997171, 8927.285817134743, 8933.0, 8944.88188976378, 8983.730488085368, 57415.31313291655, 57491.137632934704, 64856.0, 65796.34203657902, 66141.70866330779, 73246.0, 85649.57175214334, 93615.53192233996, 99505.50247249007, 104479.0, 11022.559097636005, 11587.8261826071, 11686.824697629372, 11707.648770536898, 12133.272003679807, 12770.23378597287, 12801.231926084465, 12860.164089334163, 13074.411651475897, 13261.867381326063, 11753.823692644446, 12046.457909393923, 12095.939520302341, 12168.452419641315, 12504.249745015328, 11938.462769175581, 12001.219920705125, 12128.878711212776, 12248.081393895316, 12526.0, 11899.524019039141, 11981.28112313264, 12052.698682533006, 12090.395480225778, 12241.938790305992, 8475.83048339042, 10135.64525241613, 10189.337693049929, 10200.88755051887, 10231.437270949871, 8532.786680333042, 8967.417117887317, 9121.72910204383, 10218.897811021794, 10483.37099774016], "value": 7554.921276162476, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1573.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 118.0, 117.0, 116.0, 117.0, 125.0, 167.0, 120.0, 119.0, 118.0], "value": 123.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 114.0, 131.0, 193.0, 105.0, 145.0, 149.0, 149.0, 142.0, 117.0], "value": 139.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 275.0, 255.0, 194.0, 196.0, 199.0, 199.0, 194.0, 194.0, 195.0], "value": 209.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 58.0, 84.0, 60.0, 60.0, 59.0, 62.0, 81.0, 66.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 86.0, 75.0, 68.0, 69.0, 79.0, 99.0, 105.0, 122.0], "value": 86.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 88.0, 76.0, 136.0, 127.0, 89.0, 88.0, 90.0, 85.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 126.0, 96.0, 141.0, 97.0, 100.0, 107.0, 90.0, 108.0, 127.0], "value": 114.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 92.0, 92.0, 129.0, 87.0, 79.0, 144.0, 76.0, 104.0, 108.0], "value": 100.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 55.0, 55.0, 58.0, 58.0, 56.0, 55.0, 57.0, 56.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 72.0, 104.0, 79.0, 76.0, 70.0, 69.0, 71.0, 71.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 103.0, 75.0, 112.0, 109.0, 112.0, 95.0, 89.0, 87.0, 90.0], "value": 95.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 145.0, 145.0, 150.0, 146.0, 152.0, 198.0, 145.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 178.0, 174.0, 171.0, 182.0, 213.0, 235.0, 203.0, 172.0, 172.0], "value": 187.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 69.0, 73.0, 73.0, 67.0, 66.0, 71.0, 69.0], "value": 69.6, "unit": "score"}]}]} 09:38:01 INFO - Return code: 0 09:38:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:38:01 ERROR - # TBPL SUCCESS # 09:38:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:01 INFO - 2016-10-07 09:38:01.726 screenresolution[1675:10411] starting screenresolution argv=screenresolution get 09:38:01 INFO - 2016-10-07 09:38:01.728 screenresolution[1675:10411] Display 0: 1600x1200x32@60 09:38:01 INFO - 2016-10-07 09:38:01.734 screenresolution[1676:10414] starting screenresolution argv=screenresolution list 09:38:01 INFO - Available Modes on Display 0 09:38:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:01 INFO - Intel Iris: 09:38:01 INFO - Chipset Model: Intel Iris 09:38:01 INFO - Type: GPU 09:38:01 INFO - Bus: Built-In 09:38:01 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:01 INFO - Vendor: Intel (0x8086) 09:38:01 INFO - Device ID: 0x0a2e 09:38:01 INFO - Revision ID: 0x0009 09:38:01 INFO - Displays: 09:38:01 INFO - Display: 09:38:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:01 INFO - Display Serial Number: 1600x1200 60 09:38:01 INFO - Main Display: Yes 09:38:01 INFO - Mirror: Off 09:38:01 INFO - Online: Yes 09:38:01 INFO - Rotation: Supported 09:38:01 INFO - Return code: 0 09:38:01 INFO - Running post-action listener: _resource_record_post_action 09:38:01 INFO - [mozharness: 2016-10-07 16:38:01.968934Z] Finished run-tests step (success) 09:38:01 INFO - Running post-run listener: _resource_record_post_run 09:38:02 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37435904; Write bytes: 711132672; Read time: 381; Write time: 2059 09:38:02 INFO - TinderboxPrint: CPU usage
54.5% 09:38:02 INFO - TinderboxPrint: I/O read bytes / time
37,435,904 / 381 09:38:02 INFO - TinderboxPrint: I/O write bytes / time
711,132,672 / 2,059 09:38:02 INFO - TinderboxPrint: CPU idle
2,206.0 (45.5%) 09:38:02 INFO - TinderboxPrint: CPU system
987.1 (20.4%) 09:38:02 INFO - TinderboxPrint: CPU user
1,652.6 (34.1%) 09:38:02 INFO - TinderboxPrint: Swap in / out
445,194,240 / 0 09:38:02 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392000000; Write bytes: 386779136; Read time: 29441; Write time: 708 09:38:02 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36432384; Write bytes: 300006912; Read time: 363; Write time: 1303 09:38:02 INFO - Running post-run listener: _upload_blobber_files 09:38:02 INFO - Blob upload gear active. 09:38:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:38:02 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 09:38:02 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:38:02 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:38:02 INFO - (blobuploader) - INFO - Open directory for files ... 09:38:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:38:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:38:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:04 INFO - (blobuploader) - INFO - Done attempting. 09:38:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:38:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:38:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:05 INFO - (blobuploader) - INFO - Done attempting. 09:38:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:38:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:38:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:07 INFO - (blobuploader) - INFO - Done attempting. 09:38:07 INFO - (blobuploader) - INFO - Iteration through files over. 09:38:07 INFO - Return code: 0 09:38:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:38:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:38:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/06af2b20207aaf892a32299ed87245ce7ad40901fd1d186f0e3524145165315ae12d11f8515d7d50c01a685c896431b540dd3b505ecb362e81a0895380efac94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a39b99936e412f32d272e463facc4dd86855dd8600f8cfcbe2d177056e576066ee4bb8179a41fe1442567a9db52718e5d95c21cba5623d3589b244331e41d27a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7b0cf1db31358cab435355d9034d3de1315fc235308a831028e2e1700b7e1846d7b095426a938960685c560184a3917e82a723cac25979730db3848896f8af92"} 09:38:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:38:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:38:07 INFO - Contents: 09:38:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/06af2b20207aaf892a32299ed87245ce7ad40901fd1d186f0e3524145165315ae12d11f8515d7d50c01a685c896431b540dd3b505ecb362e81a0895380efac94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a39b99936e412f32d272e463facc4dd86855dd8600f8cfcbe2d177056e576066ee4bb8179a41fe1442567a9db52718e5d95c21cba5623d3589b244331e41d27a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7b0cf1db31358cab435355d9034d3de1315fc235308a831028e2e1700b7e1846d7b095426a938960685c560184a3917e82a723cac25979730db3848896f8af92"} 09:38:07 INFO - Running post-run listener: copy_logs_to_upload_dir 09:38:07 INFO - Copying logs to upload dir... 09:38:07 INFO - mkdir: /builds/slave/test/build/upload/logs 09:38:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.771677 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-07 09:38:07.840796) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-07 09:38:07.846382) ========= 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.kgMleUVsEB/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.TA6MAjnkqL/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/06af2b20207aaf892a32299ed87245ce7ad40901fd1d186f0e3524145165315ae12d11f8515d7d50c01a685c896431b540dd3b505ecb362e81a0895380efac94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a39b99936e412f32d272e463facc4dd86855dd8600f8cfcbe2d177056e576066ee4bb8179a41fe1442567a9db52718e5d95c21cba5623d3589b244331e41d27a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7b0cf1db31358cab435355d9034d3de1315fc235308a831028e2e1700b7e1846d7b095426a938960685c560184a3917e82a723cac25979730db3848896f8af92"} build_url:https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/06af2b20207aaf892a32299ed87245ce7ad40901fd1d186f0e3524145165315ae12d11f8515d7d50c01a685c896431b540dd3b505ecb362e81a0895380efac94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a39b99936e412f32d272e463facc4dd86855dd8600f8cfcbe2d177056e576066ee4bb8179a41fe1442567a9db52718e5d95c21cba5623d3589b244331e41d27a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7b0cf1db31358cab435355d9034d3de1315fc235308a831028e2e1700b7e1846d7b095426a938960685c560184a3917e82a723cac25979730db3848896f8af92"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-07 09:38:07.879347) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 09:38:07.879693) ========= 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.kgMleUVsEB/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.TA6MAjnkqL/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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.005105 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 09:38:07.927634) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-07 09:38:07.927965) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-07 09:38:07.928302) ========= ========= Total master_lag: 0.17 =========