builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0154 starttime: 1470629683.07 results: success (0) buildid: 20160807193952 builduid: 362ebdcec94948c99c9551505622ace5 revision: 06eca66de01d82da85866faca70fad0e7a489fc7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 21:14:43.067174) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 21:14:43.067900) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 21:14:43.068170) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 21:14:43.200338) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 21:14:43.200681) ========= 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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-08-07 21:14:43-- 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% 67.7M=0s 2016-08-07 21:14:43 (67.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.176649 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 21:14:43.435221) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 21:14:43.435532) ========= 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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.020411 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 21:14:43.489212) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-07 21:14:43.489739) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 06eca66de01d82da85866faca70fad0e7a489fc7 --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 06eca66de01d82da85866faca70fad0e7a489fc7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-08-07 21:14:43,577 truncating revision to first 12 chars 2016-08-07 21:14:43,577 Setting DEBUG logging. 2016-08-07 21:14:43,577 attempt 1/10 2016-08-07 21:14:43,577 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/06eca66de01d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-07 21:14:45,522 unpacking tar archive at: autoland-06eca66de01d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.196642 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-07 21:14:45.730854) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 21:14:45.731121) ========= script_repo_revision: 06eca66de01d82da85866faca70fad0e7a489fc7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 21:14:45.731481) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 21:14:45.735161) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 21:14:45.767840) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-08-07 21:14:45.768119) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 21:14:45 INFO - MultiFileLogger online at 20160807 21:14:45 in /builds/slave/test 21:14:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 21:14:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:14:45 INFO - {'append_to_log': False, 21:14:45 INFO - 'base_work_dir': '/builds/slave/test', 21:14:45 INFO - 'blob_upload_branch': 'Autoland', 21:14:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:14:45 INFO - 'branch': 'Autoland', 21:14:45 INFO - 'buildbot_json_path': 'buildprops.json', 21:14:45 INFO - 'config_files': ('talos/mac_config.py',), 21:14:45 INFO - 'default_actions': ('clobber', 21:14:45 INFO - 'read-buildbot-config', 21:14:45 INFO - 'download-and-extract', 21:14:45 INFO - 'populate-webroot', 21:14:45 INFO - 'create-virtualenv', 21:14:45 INFO - 'install', 21:14:45 INFO - 'run-tests'), 21:14:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:14:45 INFO - 'download_minidump_stackwalk': True, 21:14:45 INFO - 'download_symbols': 'ondemand', 21:14:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:14:45 INFO - 'tooltool.py': '/tools/tooltool.py', 21:14:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:14:45 INFO - '/tools/misc-python/virtualenv.py')}, 21:14:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:14:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:14:45 INFO - 'installer_path': 'installer.exe', 21:14:45 INFO - 'log_level': 'info', 21:14:45 INFO - 'log_name': 'talos', 21:14:45 INFO - 'log_to_console': True, 21:14:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:14:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:14:45 INFO - 'opt_config_files': (), 21:14:45 INFO - 'pip_index': False, 21:14:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:14:45 INFO - 'cmd': ('bash', 21:14:45 INFO - '-c', 21:14:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:14:45 INFO - 'enabled': True, 21:14:45 INFO - 'halt_on_failure': False, 21:14:45 INFO - 'name': 'check_screen_resolution'},), 21:14:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:14:45 INFO - 'cmd': ('bash', 21:14:45 INFO - '-c', 21:14:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:14:45 INFO - 'enabled': True, 21:14:45 INFO - 'halt_on_failure': False, 21:14:45 INFO - 'name': 'check_screen_resolution'},), 21:14:45 INFO - 'run_cmd_checks_enabled': True, 21:14:45 INFO - 'sps_profile': False, 21:14:45 INFO - 'sps_profile_interval': 0, 21:14:45 INFO - 'suite': 'dromaeojs', 21:14:45 INFO - 'system_bits': '32', 21:14:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:14:45 INFO - 'title': 't-yosemite-r7-0154', 21:14:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:14:45 INFO - 'use_talos_json': True, 21:14:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:14:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:14:45 INFO - 'work_dir': 'build'} 21:14:45 INFO - [mozharness: 2016-08-08 04:14:45.962788Z] Running clobber step. 21:14:45 INFO - Running pre-action listener: _resource_record_pre_action 21:14:45 INFO - Running main action method: clobber 21:14:45 INFO - rmtree: /builds/slave/test/build 21:14:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:14:47 INFO - Running post-action listener: _resource_record_post_action 21:14:47 INFO - [mozharness: 2016-08-08 04:14:47.411676Z] Finished clobber step (success) 21:14:47 INFO - [mozharness: 2016-08-08 04:14:47.411802Z] Running read-buildbot-config step. 21:14:47 INFO - Running pre-action listener: _resource_record_pre_action 21:14:47 INFO - Running main action method: read_buildbot_config 21:14:47 INFO - Using buildbot properties: 21:14:47 INFO - { 21:14:47 INFO - "project": "", 21:14:47 INFO - "product": "firefox", 21:14:47 INFO - "script_repo_revision": "production", 21:14:47 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 21:14:47 INFO - "repository": "", 21:14:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 21:14:47 INFO - "buildid": "20160807193952", 21:14:47 INFO - "pgo_build": "False", 21:14:47 INFO - "basedir": "/builds/slave/test", 21:14:47 INFO - "buildnumber": 52, 21:14:47 INFO - "slavename": "t-yosemite-r7-0154", 21:14:47 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 21:14:47 INFO - "platform": "yosemite_r7", 21:14:47 INFO - "scheduler": "tests-autoland-macosx64-talos", 21:14:47 INFO - "branch": "autoland", 21:14:47 INFO - "repo_path": "integration/autoland", 21:14:47 INFO - "revision": "06eca66de01d82da85866faca70fad0e7a489fc7", 21:14:47 INFO - "stage_platform": "macosx64", 21:14:47 INFO - "builduid": "362ebdcec94948c99c9551505622ace5", 21:14:47 INFO - "slavebuilddir": "test" 21:14:47 INFO - } 21:14:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 21:14:47 INFO - Running post-action listener: _resource_record_post_action 21:14:47 INFO - [mozharness: 2016-08-08 04:14:47.415904Z] Finished read-buildbot-config step (success) 21:14:47 INFO - [mozharness: 2016-08-08 04:14:47.416046Z] Running download-and-extract step. 21:14:47 INFO - Running pre-action listener: _resource_record_pre_action 21:14:47 INFO - Running main action method: download_and_extract 21:14:47 INFO - mkdir: /builds/slave/test/build/tests 21:14: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')]} 21:14:47 INFO - trying https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 21:14:47 INFO - Downloading https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 21:14:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 21:14:48 INFO - Downloaded 1199 bytes. 21:14:48 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 21:14:48 INFO - Using the following test package requirements: 21:14:48 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:14:48 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:48 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 21:14:48 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:48 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 21:14:48 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:48 INFO - u'jsshell-mac.zip'], 21:14:48 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:48 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 21:14:48 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:14:48 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:48 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 21:14:48 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:48 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 21:14:48 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:48 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 21:14:48 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:48 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 21:14:48 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:14: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')]} 21:14:48 INFO - trying https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:14:48 INFO - Downloading https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:14:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:14:49 INFO - Downloaded 16324183 bytes. 21:14:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:14:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:14:52 INFO - Return code: 0 21:14:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:14:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:14:52 INFO - trying https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:14:52 INFO - Downloading https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:14:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:14:53 INFO - Downloaded 16324183 bytes. 21:14:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:14:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:14:57 INFO - Return code: 0 21:14:57 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')]} 21:14:57 INFO - trying https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 21:14:57 INFO - Downloading https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 21:14:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:14:58 INFO - Downloaded 14413723 bytes. 21:14:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:14:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 21:14:59 INFO - Return code: 0 21:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:14:59 INFO - trying https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:14:59 INFO - Downloading https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 21:14:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 21:15:13 INFO - Downloaded 111968966 bytes. 21:15:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:15:13 INFO - mkdir: /builds/slave/test/properties 21:15:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:15:13 INFO - Writing to file /builds/slave/test/properties/build_url 21:15:13 INFO - Contents: 21:15:13 INFO - build_url:https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:15:14 INFO - Running post-action listener: _resource_record_post_action 21:15:14 INFO - Running post-action listener: set_extra_try_arguments 21:15:14 INFO - [mozharness: 2016-08-08 04:15:14.296884Z] Finished download-and-extract step (success) 21:15:14 INFO - [mozharness: 2016-08-08 04:15:14.297005Z] Running populate-webroot step. 21:15:14 INFO - Running pre-action listener: _resource_record_pre_action 21:15:14 INFO - Running main action method: populate_webroot 21:15:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:15:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:15:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:15:14 INFO - '--tppagecycles', 21:15:14 INFO - '7'], 21:15:14 INFO - 'tests': ['tsvgm']}}, 21:15:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:15:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:15:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:15:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:15:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:15:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:15:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:14 INFO - 'talos_options': ['--disable-e10s'], 21:15:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:15:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:15:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:14 INFO - 'talos_options': ['--disable-e10s'], 21:15:14 INFO - 'tests': ['damp', 'tps']}, 21:15:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:14 INFO - 'tests': ['damp', 'tps']}, 21:15:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:15:14 INFO - 'tests': ['dromaeo_dom']}, 21:15:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:15:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:15:14 INFO - 'tests': ['basic_compositor_video']}, 21:15:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:15:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:15:14 INFO - 'tests': ['a11yr', 21:15:14 INFO - 'ts_paint', 21:15:14 INFO - 'tpaint', 21:15:14 INFO - 'sessionrestore', 21:15:14 INFO - 'sessionrestore_no_auto_restore', 21:15:14 INFO - 'tabpaint']}, 21:15:14 INFO - 'other-e10s': {'tests': ['a11yr', 21:15:14 INFO - 'ts_paint', 21:15:14 INFO - 'tpaint', 21:15:14 INFO - 'sessionrestore', 21:15:14 INFO - 'sessionrestore_no_auto_restore', 21:15:14 INFO - 'tabpaint']}, 21:15:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:15:14 INFO - 'ts_paint', 21:15:14 INFO - 'tpaint', 21:15:14 INFO - 'sessionrestore', 21:15:14 INFO - 'sessionrestore_no_auto_restore', 21:15:14 INFO - 'tabpaint']}, 21:15:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:15:14 INFO - 'ts_paint', 21:15:14 INFO - 'tpaint', 21:15:14 INFO - 'sessionrestore', 21:15:14 INFO - 'sessionrestore_no_auto_restore', 21:15:14 INFO - 'tabpaint']}, 21:15:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:15:14 INFO - 'tests': ['a11yr', 21:15:14 INFO - 'ts_paint', 21:15:14 INFO - 'tpaint', 21:15:14 INFO - 'sessionrestore', 21:15:14 INFO - 'sessionrestore_no_auto_restore', 21:15:14 INFO - 'tabpaint']}, 21:15:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:15:14 INFO - 'tests': ['a11yr', 21:15:14 INFO - 'ts_paint', 21:15:14 INFO - 'tpaint', 21:15:14 INFO - 'sessionrestore', 21:15:14 INFO - 'sessionrestore_no_auto_restore', 21:15:14 INFO - 'tabpaint']}, 21:15:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:15:14 INFO - 'tests': ['tsvgx', 21:15:14 INFO - 'tsvgr_opacity', 21:15:14 INFO - 'tart', 21:15:14 INFO - 'tscrollx', 21:15:14 INFO - 'cart']}, 21:15:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:15:14 INFO - 'tsvgr_opacity', 21:15:14 INFO - 'tart', 21:15:14 INFO - 'tscrollx', 21:15:14 INFO - 'cart']}, 21:15:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:14 INFO - 'talos_options': ['--disable-e10s'], 21:15:14 INFO - 'tests': ['tp5o']}, 21:15:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:14 INFO - 'tests': ['tp5o']}, 21:15:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:14 INFO - 'talos_options': ['--disable-e10s', 21:15:14 INFO - '--xperf_path', 21:15:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:15:14 INFO - 'tests': ['tp5n']}, 21:15:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:14 INFO - 'talos_options': ['--xperf_path', 21:15:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:15:14 INFO - 'tests': ['tp5n']}}, 21:15:14 INFO - 'talos.zip': {'path': '', 21:15:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:15:14 INFO - Running post-action listener: _resource_record_post_action 21:15:14 INFO - [mozharness: 2016-08-08 04:15:14.315614Z] Finished populate-webroot step (success) 21:15:14 INFO - [mozharness: 2016-08-08 04:15:14.315750Z] Running create-virtualenv step. 21:15:14 INFO - Running pre-action listener: _resource_record_pre_action 21:15:14 INFO - Running main action method: create_virtualenv 21:15:14 INFO - Creating virtualenv /builds/slave/test/build/venv 21:15:14 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 21:15:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:15:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:15:14 INFO - Using real prefix '/tools/python27' 21:15:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:15:15 INFO - Installing distribute.............................................................................................................................................................................................done. 21:15:17 INFO - Installing pip.................done. 21:15:17 INFO - Return code: 0 21:15:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:15:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100ba9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9223cef4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b0dd30>, '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 0x100872db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b05b20>, '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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 21:15:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:15:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5EFcxbS4x3/Render', 21:15:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:18 INFO - 'HOME': '/Users/cltbld', 21:15:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:18 INFO - 'LOGNAME': 'cltbld', 21:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:18 INFO - 'MOZ_NO_REMOTE': '1', 21:15:18 INFO - 'NO_EM_RESTART': '1', 21:15:18 INFO - 'PAGER': '/bin/cat', 21:15:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:18 INFO - 'PWD': '/builds/slave/test', 21:15:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:18 INFO - 'SHELL': '/bin/bash', 21:15:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Va9ZpPB7KQ/Listeners', 21:15:18 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 21:15:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:18 INFO - 'USER': 'cltbld', 21:15:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:18 INFO - 'XPC_FLAGS': '0x0', 21:15:18 INFO - 'XPC_SERVICE_NAME': '0', 21:15:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:18 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 21:15:18 INFO - Cleaning up... 21:15:18 INFO - Return code: 0 21:15:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:15:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100ba9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9223cef4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b0dd30>, '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 0x100872db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b05b20>, '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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 21:15:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:15:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5EFcxbS4x3/Render', 21:15:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:18 INFO - 'HOME': '/Users/cltbld', 21:15:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:18 INFO - 'LOGNAME': 'cltbld', 21:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:18 INFO - 'MOZ_NO_REMOTE': '1', 21:15:18 INFO - 'NO_EM_RESTART': '1', 21:15:18 INFO - 'PAGER': '/bin/cat', 21:15:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:18 INFO - 'PWD': '/builds/slave/test', 21:15:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:18 INFO - 'SHELL': '/bin/bash', 21:15:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Va9ZpPB7KQ/Listeners', 21:15:18 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 21:15:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:18 INFO - 'USER': 'cltbld', 21:15:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:18 INFO - 'XPC_FLAGS': '0x0', 21:15:18 INFO - 'XPC_SERVICE_NAME': '0', 21:15:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:18 INFO - Downloading/unpacking psutil>=3.1.1 21:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:15:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:15:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:15:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:15:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:15:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:15:21 INFO - Installing collected packages: psutil 21:15:21 INFO - Running setup.py install for psutil 21:15:22 INFO - building 'psutil._psutil_osx' extension 21:15:22 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 21:15:22 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 21:15:22 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 21:15:22 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 21:15:22 INFO - building 'psutil._psutil_posix' extension 21:15:22 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 21:15:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:15:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:15:22 INFO - ^ 21:15:22 INFO - 1 warning generated. 21:15:22 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 21:15:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:15:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:15:22 INFO - Successfully installed psutil 21:15:22 INFO - Cleaning up... 21:15:22 INFO - Return code: 0 21:15:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:15:22 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')]} 21:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:22 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')]} 21:15:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:22 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 0x100ba9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9223cef4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b0dd30>, '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 0x100872db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b05b20>, '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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 21:15:22 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 21:15:22 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 21:15:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5EFcxbS4x3/Render', 21:15:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:22 INFO - 'HOME': '/Users/cltbld', 21:15:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:22 INFO - 'LOGNAME': 'cltbld', 21:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:22 INFO - 'MOZ_NO_REMOTE': '1', 21:15:22 INFO - 'NO_EM_RESTART': '1', 21:15:22 INFO - 'PAGER': '/bin/cat', 21:15:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:22 INFO - 'PWD': '/builds/slave/test', 21:15:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:22 INFO - 'SHELL': '/bin/bash', 21:15:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Va9ZpPB7KQ/Listeners', 21:15:22 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 21:15:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:22 INFO - 'USER': 'cltbld', 21:15:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:22 INFO - 'XPC_FLAGS': '0x0', 21:15:22 INFO - 'XPC_SERVICE_NAME': '0', 21:15:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:15:23 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 21:15:23 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 21:15:23 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 21:15:23 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 21:15:23 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 21:15:23 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 21:15:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:15:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:15:26 INFO - Installing collected packages: mozsystemmonitor 21:15:26 INFO - Running setup.py install for mozsystemmonitor 21:15:26 INFO - Successfully installed mozsystemmonitor 21:15:26 INFO - Cleaning up... 21:15:26 INFO - Return code: 0 21:15:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:15: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')]} 21:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15: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')]} 21:15:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15: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', '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 0x100ba9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9223cef4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b0dd30>, '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 0x100872db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b05b20>, '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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 21:15: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:15: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 jsonschema==2.5.1 21:15:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5EFcxbS4x3/Render', 21:15:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:26 INFO - 'HOME': '/Users/cltbld', 21:15:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:26 INFO - 'LOGNAME': 'cltbld', 21:15:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:26 INFO - 'MOZ_NO_REMOTE': '1', 21:15:26 INFO - 'NO_EM_RESTART': '1', 21:15:26 INFO - 'PAGER': '/bin/cat', 21:15:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:26 INFO - 'PWD': '/builds/slave/test', 21:15:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:26 INFO - 'SHELL': '/bin/bash', 21:15:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Va9ZpPB7KQ/Listeners', 21:15:26 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 21:15:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:26 INFO - 'USER': 'cltbld', 21:15:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:26 INFO - 'XPC_FLAGS': '0x0', 21:15:26 INFO - 'XPC_SERVICE_NAME': '0', 21:15:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:26 INFO - Downloading/unpacking jsonschema==2.5.1 21:15: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 21:15: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 21:15: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 21:15: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 21:15: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 21:15: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 21:15:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:15:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:15: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 21:15: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 21:15: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 21:15: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 21:15: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 21:15: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 21:15:29 INFO - Downloading functools32-3.2.3-2.tar.gz 21:15:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:15:29 INFO - warning: no files found matching '*.txt' 21:15:29 INFO - no previously-included directories found matching 'build' 21:15:29 INFO - no previously-included directories found matching 'dist' 21:15:29 INFO - no previously-included directories found matching '.git*' 21:15:29 INFO - Installing collected packages: jsonschema, functools32 21:15:29 INFO - Running setup.py install for functools32 21:15:29 INFO - warning: no files found matching '*.txt' 21:15:29 INFO - no previously-included directories found matching 'build' 21:15:29 INFO - no previously-included directories found matching 'dist' 21:15:29 INFO - no previously-included directories found matching '.git*' 21:15:30 INFO - Successfully installed jsonschema functools32 21:15:30 INFO - Cleaning up... 21:15:30 INFO - Return code: 0 21:15:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:15:30 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')]} 21:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:30 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')]} 21:15:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:30 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 0x100ba9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9223cef4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b0dd30>, '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 0x100872db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b05b20>, '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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 21:15:30 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 21:15:30 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 21:15:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5EFcxbS4x3/Render', 21:15:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:30 INFO - 'HOME': '/Users/cltbld', 21:15:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:30 INFO - 'LOGNAME': 'cltbld', 21:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:30 INFO - 'MOZ_NO_REMOTE': '1', 21:15:30 INFO - 'NO_EM_RESTART': '1', 21:15:30 INFO - 'PAGER': '/bin/cat', 21:15:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:30 INFO - 'PWD': '/builds/slave/test', 21:15:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:30 INFO - 'SHELL': '/bin/bash', 21:15:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Va9ZpPB7KQ/Listeners', 21:15:30 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 21:15:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:30 INFO - 'USER': 'cltbld', 21:15:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:30 INFO - 'XPC_FLAGS': '0x0', 21:15:30 INFO - 'XPC_SERVICE_NAME': '0', 21:15:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:15:30 INFO - Cleaning up... 21:15:30 INFO - Return code: 0 21:15:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:15:30 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')]} 21:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:30 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')]} 21:15:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:30 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 0x100ba9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9223cef4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b0dd30>, '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 0x100872db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b05b20>, '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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 21:15:30 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 21:15:30 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 21:15:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5EFcxbS4x3/Render', 21:15:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:30 INFO - 'HOME': '/Users/cltbld', 21:15:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:30 INFO - 'LOGNAME': 'cltbld', 21:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:30 INFO - 'MOZ_NO_REMOTE': '1', 21:15:30 INFO - 'NO_EM_RESTART': '1', 21:15:30 INFO - 'PAGER': '/bin/cat', 21:15:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:30 INFO - 'PWD': '/builds/slave/test', 21:15:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:30 INFO - 'SHELL': '/bin/bash', 21:15:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Va9ZpPB7KQ/Listeners', 21:15:30 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 21:15:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:30 INFO - 'USER': 'cltbld', 21:15:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:30 INFO - 'XPC_FLAGS': '0x0', 21:15:30 INFO - 'XPC_SERVICE_NAME': '0', 21:15:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:30 INFO - Downloading/unpacking blobuploader==1.2.4 21:15:30 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 21:15:30 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 21:15:30 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 21:15:30 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 21:15:30 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 21:15:30 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 21:15:33 INFO - Downloading blobuploader-1.2.4.tar.gz 21:15:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:15:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:15: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 21:15: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 21:15: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 21:15: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 21:15: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 21:15: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 21:15:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:15:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:15: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 21:15: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 21:15: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 21:15: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 21:15: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 21:15: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 21:15:34 INFO - Downloading docopt-0.6.1.tar.gz 21:15:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:15:34 INFO - Installing collected packages: blobuploader, requests, docopt 21:15:34 INFO - Running setup.py install for blobuploader 21:15:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:15:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:15:34 INFO - Running setup.py install for requests 21:15:35 INFO - Running setup.py install for docopt 21:15:35 INFO - Successfully installed blobuploader requests docopt 21:15:35 INFO - Cleaning up... 21:15:35 INFO - Return code: 0 21:15:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:15:35 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')]} 21:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:35 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')]} 21:15:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:35 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 0x100ba9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9223cef4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b0dd30>, '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 0x100872db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b05b20>, '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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 21:15:35 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 21:15:35 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 21:15:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5EFcxbS4x3/Render', 21:15:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:35 INFO - 'HOME': '/Users/cltbld', 21:15:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:35 INFO - 'LOGNAME': 'cltbld', 21:15:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:35 INFO - 'MOZ_NO_REMOTE': '1', 21:15:35 INFO - 'NO_EM_RESTART': '1', 21:15:35 INFO - 'PAGER': '/bin/cat', 21:15:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:35 INFO - 'PWD': '/builds/slave/test', 21:15:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:35 INFO - 'SHELL': '/bin/bash', 21:15:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Va9ZpPB7KQ/Listeners', 21:15:35 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 21:15:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:35 INFO - 'USER': 'cltbld', 21:15:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:35 INFO - 'XPC_FLAGS': '0x0', 21:15:35 INFO - 'XPC_SERVICE_NAME': '0', 21:15:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:15:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-T5tJkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:15:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-3YblGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:15:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-qYPDZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:15:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-RoD_Lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:15:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-iPdAxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:15:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-HkHofr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:15:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-c4rKWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:15:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-MJ0IzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:15:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-nFCQk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:15:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-NTEhOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:15:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-PisT5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:15:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-QaxKxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:15:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-2KrmrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:15:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ey6K91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:15:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-77R25T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:15:37 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-FoHRt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:15:37 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-hhT6Xd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:15:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:15:37 INFO - Running setup.py install for manifestparser 21:15:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:15:37 INFO - Running setup.py install for mozcrash 21:15:37 INFO - Running setup.py install for mozdebug 21:15:37 INFO - Running setup.py install for mozdevice 21:15:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:15:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:15:37 INFO - Running setup.py install for mozfile 21:15:38 INFO - Running setup.py install for mozhttpd 21:15:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:15:38 INFO - Running setup.py install for mozinfo 21:15:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:15:38 INFO - Running setup.py install for mozInstall 21:15:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:15:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:15:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:15:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:15:38 INFO - Running setup.py install for mozleak 21:15:38 INFO - Running setup.py install for mozlog 21:15:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:15:38 INFO - Running setup.py install for moznetwork 21:15:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:15:38 INFO - Running setup.py install for mozprocess 21:15:39 INFO - Running setup.py install for mozprofile 21:15:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:15:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:15:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:15:39 INFO - Running setup.py install for mozrunner 21:15:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:15:39 INFO - Running setup.py install for mozscreenshot 21:15:39 INFO - Running setup.py install for moztest 21:15:39 INFO - Running setup.py install for mozversion 21:15:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:15:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:15:39 INFO - Cleaning up... 21:15:39 INFO - Return code: 0 21:15:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:15:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:15:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:39 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 0x100ba9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9223cef4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b0dd30>, '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 0x100872db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b05b20>, '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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 21:15:39 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 21:15:39 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 21:15:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5EFcxbS4x3/Render', 21:15:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:39 INFO - 'HOME': '/Users/cltbld', 21:15:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:39 INFO - 'LOGNAME': 'cltbld', 21:15:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:39 INFO - 'MOZ_NO_REMOTE': '1', 21:15:39 INFO - 'NO_EM_RESTART': '1', 21:15:39 INFO - 'PAGER': '/bin/cat', 21:15:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:39 INFO - 'PWD': '/builds/slave/test', 21:15:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:39 INFO - 'SHELL': '/bin/bash', 21:15:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Va9ZpPB7KQ/Listeners', 21:15:39 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 21:15:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:39 INFO - 'USER': 'cltbld', 21:15:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:39 INFO - 'XPC_FLAGS': '0x0', 21:15:39 INFO - 'XPC_SERVICE_NAME': '0', 21:15:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:15:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-TmkpBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:15:40 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)) 21:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:15:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-g3NhJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:15:40 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)) 21:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:15:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-LKhmWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:15:40 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)) 21:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:15:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-zTunae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:15:40 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)) 21:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:15:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-yAMHcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:15:40 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)) 21:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:15:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-3KDnRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:15:40 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)) 21:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:15:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-spCnmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:15:40 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)) 21:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:15:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-uMFY3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:15:41 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)) 21:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:15:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-BIoc1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:15:41 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)) 21:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:15:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-2jfQWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:15:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 21:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:15:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-HakK5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:15:41 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)) 21:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:15:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-sG7iiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:15:41 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)) 21:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:15:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-crB1O6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:15:41 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)) 21:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:15:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-419koc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:15:41 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)) 21:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:15:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-_D9kai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:15:41 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)) 21:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:15:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-j7h6_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:15:41 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)) 21:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:15:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-RiONPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:15:41 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)) 21:15:41 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)) 21:15:41 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)) 21:15:41 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)) 21:15:41 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)) 21:15:41 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)) 21:15:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:15:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:15:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:15:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:15:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:15:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:15:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:15:44 INFO - Downloading blessings-1.6.tar.gz 21:15:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:15:44 INFO - Installing collected packages: blessings 21:15:44 INFO - Running setup.py install for blessings 21:15:44 INFO - Successfully installed blessings 21:15:44 INFO - Cleaning up... 21:15:44 INFO - Return code: 0 21:15:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:15:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:15:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:15:45 INFO - Reading from file tmpfile_stdout 21:15:45 INFO - Current package versions: 21:15:45 INFO - blessings == 1.6 21:15:45 INFO - blobuploader == 1.2.4 21:15:45 INFO - docopt == 0.6.1 21:15:45 INFO - functools32 == 3.2.3-2 21:15:45 INFO - jsonschema == 2.5.1 21:15:45 INFO - manifestparser == 1.1 21:15:45 INFO - mozInstall == 1.12 21:15:45 INFO - mozcrash == 0.17 21:15:45 INFO - mozdebug == 0.1 21:15:45 INFO - mozdevice == 0.48 21:15:45 INFO - mozfile == 1.2 21:15:45 INFO - mozhttpd == 0.7 21:15:45 INFO - mozinfo == 0.9 21:15:45 INFO - mozleak == 0.1 21:15:45 INFO - mozlog == 3.3 21:15:45 INFO - moznetwork == 0.27 21:15:45 INFO - mozprocess == 0.23 21:15:45 INFO - mozprofile == 0.28 21:15:45 INFO - mozrunner == 6.12 21:15:45 INFO - mozscreenshot == 0.1 21:15:45 INFO - mozsystemmonitor == 0.3 21:15:45 INFO - moztest == 0.7 21:15:45 INFO - mozversion == 1.4 21:15:45 INFO - psutil == 3.1.1 21:15:45 INFO - requests == 1.2.3 21:15:45 INFO - wsgiref == 0.1.2 21:15:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:15:45 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')]} 21:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:45 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')]} 21:15:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:45 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 0x100ba9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9223cef4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b0dd30>, '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 0x100872db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b05b20>, '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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 21:15:45 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 21:15:45 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 21:15:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5EFcxbS4x3/Render', 21:15:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:45 INFO - 'HOME': '/Users/cltbld', 21:15:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:45 INFO - 'LOGNAME': 'cltbld', 21:15:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:45 INFO - 'MOZ_NO_REMOTE': '1', 21:15:45 INFO - 'NO_EM_RESTART': '1', 21:15:45 INFO - 'PAGER': '/bin/cat', 21:15:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:45 INFO - 'PWD': '/builds/slave/test', 21:15:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:45 INFO - 'SHELL': '/bin/bash', 21:15:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Va9ZpPB7KQ/Listeners', 21:15:45 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 21:15:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:45 INFO - 'USER': 'cltbld', 21:15:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:45 INFO - 'XPC_FLAGS': '0x0', 21:15:45 INFO - 'XPC_SERVICE_NAME': '0', 21:15:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:45 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)) 21:15:45 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)) 21:15:45 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)) 21:15:45 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)) 21:15:45 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)) 21:15:45 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)) 21:15:45 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)) 21:15:45 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)) 21:15:45 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)) 21:15:45 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)) 21:15:45 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)) 21:15:45 INFO - Cleaning up... 21:15:45 INFO - Return code: 0 21:15:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:15:45 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')]} 21:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:45 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')]} 21:15:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:45 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 0x100ba9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9223cef4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b0dd30>, '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 0x100872db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b05b20>, '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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 21:15:45 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 21:15:45 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 21:15:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5EFcxbS4x3/Render', 21:15:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:45 INFO - 'HOME': '/Users/cltbld', 21:15:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:45 INFO - 'LOGNAME': 'cltbld', 21:15:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:45 INFO - 'MOZ_NO_REMOTE': '1', 21:15:45 INFO - 'NO_EM_RESTART': '1', 21:15:45 INFO - 'PAGER': '/bin/cat', 21:15:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:45 INFO - 'PWD': '/builds/slave/test', 21:15:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:45 INFO - 'SHELL': '/bin/bash', 21:15:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Va9ZpPB7KQ/Listeners', 21:15:45 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 21:15:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:45 INFO - 'USER': 'cltbld', 21:15:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:45 INFO - 'XPC_FLAGS': '0x0', 21:15:45 INFO - 'XPC_SERVICE_NAME': '0', 21:15:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:15:45 INFO - Cleaning up... 21:15:45 INFO - Return code: 0 21:15:45 INFO - Running post-action listener: _resource_record_post_action 21:15:45 INFO - Running post-action listener: _start_resource_monitoring 21:15:45 INFO - Starting resource monitoring. 21:15:45 INFO - [mozharness: 2016-08-08 04:15:45.780193Z] Finished create-virtualenv step (success) 21:15:45 INFO - [mozharness: 2016-08-08 04:15:45.781148Z] Running install step. 21:15:45 INFO - Running pre-action listener: _resource_record_pre_action 21:15:45 INFO - Running main action method: install 21:15:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:15:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:15:46 INFO - Reading from file tmpfile_stdout 21:15:46 INFO - Detecting whether we're running mozinstall >=1.0... 21:15:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:15:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:15:46 INFO - Reading from file tmpfile_stdout 21:15:46 INFO - Output received: 21:15:46 INFO - Usage: mozinstall [options] installer 21:15:46 INFO - Options: 21:15:46 INFO - -h, --help show this help message and exit 21:15:46 INFO - -d DEST, --destination=DEST 21:15:46 INFO - Directory to install application into. [default: 21:15:46 INFO - "/builds/slave/test"] 21:15:46 INFO - --app=APP Application being installed. [default: firefox] 21:15:46 INFO - mkdir: /builds/slave/test/build/application 21:15:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:15:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:16:20 INFO - Reading from file tmpfile_stdout 21:16:20 INFO - Output received: 21:16:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:16:20 INFO - Running post-action listener: _resource_record_post_action 21:16:20 INFO - [mozharness: 2016-08-08 04:16:20.471898Z] Finished install step (success) 21:16:20 INFO - [mozharness: 2016-08-08 04:16:20.472045Z] Running run-tests step. 21:16:20 INFO - Running pre-action listener: _resource_record_pre_action 21:16:20 INFO - Running main action method: run_tests 21:16:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:16:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:16:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:16:20 INFO - 2016-08-07 21:16:20.485 screenresolution[1933:16077] starting screenresolution argv=screenresolution get 21:16:20 INFO - 2016-08-07 21:16:20.488 screenresolution[1933:16077] Display 0: 1600x1200x32@60 21:16:20 INFO - 2016-08-07 21:16:20.494 screenresolution[1934:16079] starting screenresolution argv=screenresolution list 21:16:20 INFO - Available Modes on Display 0 21:16:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:16:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:16:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:16:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:16:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:16:20 INFO - Intel Iris: 21:16:20 INFO - Chipset Model: Intel Iris 21:16:20 INFO - Type: GPU 21:16:20 INFO - Bus: Built-In 21:16:20 INFO - VRAM (Dynamic, Max): 1536 MB 21:16:20 INFO - Vendor: Intel (0x8086) 21:16:20 INFO - Device ID: 0x0a2e 21:16:20 INFO - Revision ID: 0x0009 21:16:20 INFO - Displays: 21:16:20 INFO - Display: 21:16:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:16:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:16:20 INFO - Display Serial Number: 1600x1200 60 21:16:20 INFO - Main Display: Yes 21:16:20 INFO - Mirror: Off 21:16:20 INFO - Online: Yes 21:16:20 INFO - Rotation: Supported 21:16:20 INFO - Return code: 0 21:16:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:16:20 INFO - Python 2.7.3 21:16:20 INFO - Return code: 0 21:16:20 INFO - grabbing minidump binary from tooltool 21:16:20 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')]} 21:16:20 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 0x100b0dd30>, '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 0x100872db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b05b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:16:20 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 21:16:20 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 21:16:20 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 21:16:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:16:20 INFO - Return code: 0 21:16:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:16:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:16:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:16:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:16:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:16:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0154 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:16:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5EFcxbS4x3/Render', 21:16:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:16:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:16:20 INFO - 'HOME': '/Users/cltbld', 21:16:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:16:20 INFO - 'LOGNAME': 'cltbld', 21:16:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:16:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:20 INFO - 'MOZ_NO_REMOTE': '1', 21:16:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:16:20 INFO - 'NO_EM_RESTART': '1', 21:16:20 INFO - 'PAGER': '/bin/cat', 21:16:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:20 INFO - 'PWD': '/builds/slave/test', 21:16:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:16:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:16:20 INFO - 'SHELL': '/bin/bash', 21:16:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Va9ZpPB7KQ/Listeners', 21:16:20 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 21:16:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:16:20 INFO - 'USER': 'cltbld', 21:16:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:16:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:20 INFO - 'XPC_FLAGS': '0x0', 21:16:20 INFO - 'XPC_SERVICE_NAME': '0', 21:16:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:16:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:16:21 INFO - mozversion application_buildid: 20160807193952 21:16:21 INFO - mozversion application_changeset: 06eca66de01d82da85866faca70fad0e7a489fc7 21:16:21 INFO - mozversion application_display_name: Nightly 21:16:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:16:21 INFO - mozversion application_name: Firefox 21:16:21 INFO - mozversion application_remotingname: firefox 21:16:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:16:21 INFO - mozversion application_vendor: Mozilla 21:16:21 INFO - mozversion application_version: 51.0a1 21:16:21 INFO - mozversion platform_buildid: 20160807193952 21:16:21 INFO - mozversion platform_changeset: 06eca66de01d82da85866faca70fad0e7a489fc7 21:16:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:16:21 INFO - mozversion platform_version: 51.0a1 21:16:21 INFO - using testdate: 1470629781 21:16:21 INFO - actual date: 1470629781 21:16:21 INFO - starting webserver on 'localhost:50743' 21:16:21 INFO - SUITE-START | Running 2 tests 21:16:21 INFO - TEST-START | dromaeo_css 21:16:21 INFO - operating with platform_type : mac_ 21:16:21 INFO - Initialising browser for dromaeo_css test... 21:16:21 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp0saxSa/profile http://localhost:50743/getInfo.html) 21:16:23 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 21:16:23 INFO - PROCESS | 1944 | colorDepth:24 21:16:23 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 21:16:23 INFO - PROCESS | 1944 | __metrics 21:16:23 INFO - PROCESS | 1944 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:16:23 INFO - PROCESS | 1944 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:16:24 INFO - TEST-INFO | 1944: exit 0 21:16:24 INFO - Browser initialized. 21:16:24 INFO - Running cycle 1/1 for dromaeo_css test... 21:16:24 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp0saxSa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:16:36 INFO - PROCESS | 1946 | RSS: Main: 243572736 21:16:36 INFO - PROCESS | 1946 | 21:17:27 INFO - PROCESS | 1946 | 1470629847515 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] 21:19:39 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50743/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50743/tests/dromaeo/cssquery-ext.html) 21:19:41 INFO - PROCESS | 1946 | RSS: Main: 365334528 21:19:41 INFO - PROCESS | 1946 | 21:22:44 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50743/tests/dromaeo/cssquery-ext.html (next: http://localhost:50743/tests/dromaeo/cssquery-jquery.html) 21:22:45 INFO - PROCESS | 1946 | RSS: Main: 400515072 21:22:45 INFO - PROCESS | 1946 | 21:25:47 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50743/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50743/tests/dromaeo/cssquery-mootools.html) 21:25:48 INFO - PROCESS | 1946 | RSS: Main: 347717632 21:25:48 INFO - PROCESS | 1946 | 21:28:50 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50743/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50743/tests/dromaeo/cssquery-prototype.html) 21:28:50 INFO - PROCESS | 1946 | RSS: Main: 351776768 21:28:50 INFO - PROCESS | 1946 | 21:31:54 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50743/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50743/tests/dromaeo/cssquery-yui.html) 21:31:54 INFO - PROCESS | 1946 | RSS: Main: 341803008 21:31:54 INFO - PROCESS | 1946 | 21:34:58 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50743/tests/dromaeo/cssquery-yui.html (next: http://localhost:50743/tests/dromaeo/cssquery-dojo.html) 21:34:58 INFO - PROCESS | 1946 | RSS: Main: 391118848 21:34:58 INFO - PROCESS | 1946 | 21:34:58 INFO - PROCESS | 1946 | __start_tp_report 21:34:58 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 21:34:58 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 21:34:58 INFO - PROCESS | 1946 | |i|pagename|runs| 21:34:58 INFO - PROCESS | 1946 | |0;dojo.html;3396.269801992571;3397.1846756778373;3406.369821583006;3428.314337132574;3455.8272086395673;5665.12190610456;5747.224124743158;5772.971135144329;5822.970885145573;5828.475437210655;5728.455796699317;5802.506786923117;5837.387074357184;5860.50185734212;5923.992921203395;5218.739063046852;5312.760925758331;5320.6275560710765;5345.545628621552;5377.946220537784;5184.2223666450145;5297.867553311179;5332.760025798829;5401.351837779453;5408.4591540845995;5322.627416080856;5322.813701520428;5335.679859208441;5369.489898459659;5394.676319420847;5183.170692689189;5286.101362768339;5312.76092575835;5322.228276899867;5329.840104796862;5177.534021938043;5235.973820130875;5242.135047717122;5257.4742525747115;5334.599905007101;5766.135079738031;5785.537157027427;5819.388964158787;5834.329052159013;5843.941560584382;6358.1734374531015;6360.141380913564;6360.332165122688;6365.1725275714325;6369.522285828535;6186.938130618726;6248.3751624837605;6278.623282603013;6306.527010474233;6313.463355614778;5893.5874488785375;5965.850853728692;5968.641881487125;5975.790847320323;5985.072313791369;6311.274203466568;6321.304656487782;6333.90499142504;6353;6364.681765911686;6079.939200608026;6121.452973575903;6125.41808528198;6150.630962142286;6213.720382582795;6286.277078135981;6317.4630156437215;6318.020706790455;6318.273398559132;6333.683315834282;6283.37166283368;6298.842528936813;6306.49548036156;6318.684065796691;6329.082283068929;6093.542984276197;6214.1921550198185;6214.844628884314;6296.8425789354715;6299.96850015747;4834.057358814997;5031.899362012739;5048.394192696826;5051.26756620288;5054.949450505522;3870.748401353903;4088.591140885947;4535.024969631545;4542.772861356919;4583.862484125481;5867.735951882176;5883.61756485827;5896.528277737771;5909.202257695242;5933.110033495011;6203.348648391854;6254.15568898202;6275.732938658134;6289.591176573508;6291.182146320949;5851;5866.354700982888;5873;5903.114532820111;5919.733611987384;5507.284053073075;5507.642003269774;5518.227448157261;5531.723413829292;5535.529479994165;6241.906371404523;6288.339724328879;6371.139896113966;6374.330695277022;6460.160179176677;6294;6309.684515774101;6310.148130002574;6321.0834429008455;6360.109584658244;6379.649119298298;6382.712777925098;6390.7763228286785;6393.2328120625825;6401.295857455583;6115.3578874217565;6167.229096362955;6236.34518375564;6256.124142619946;6273.560850740587;6241.750329986749;6253.280872699698;6291.716872740829;6308.179936608211;6308.495320374451;6163.044728067157;6236.750529978932;6237.688115594293;6241.344658810941;6244.344343843831;4744.857654270378;6074.149619053248;6117.218487018656;6265.906011409924;6265.96867015662;6015.639061656315;6058.923001393399;6074.466017966408;6084.762715153553;6112.419320164578;6192.97815860378;6219.973704338733;6221.564490485623;6221.906671399842;6233.657148856856;5892.9116063258225;5929.940700592939;6057.21256236704;6064.909026364519;6079.878402432015 21:34:58 INFO - PROCESS | 1946 | |1;ext.html;463.96520260980424;487.4516169309527;498.01890276156007;503.6677986725112;506.35439814236844;31262.84368578157;40916.18167636649;41093.17813643725;41395.17209655809;45022.32466513005;44174.779126104404;44824;44838.327425088566;44839.327410088874;44871.10257794842;8627.525486098262;9600.991895850957;9770.706878793648;9781.608735650565;9781.951090244575;8613.784655383633;11595.188336816425;11787.410629468535;11813.409329533531;12168.878311216908;11787.17489775716;11859.466324015397;11864.347460889647;11889.940550297237;11909.523619055228;11800.40997950099;11855.881441185651;11858.28850268978;11862.703432414173;11865.288082714978;11600.88399116015;11677.29936203822;11747.412629368582;11763.764724705543;11768.823467647991;40443;40812;40832.387514187314;40859;41036;3425.4348032574594;3485.9128521786906;3558.679718825318;3831.444440556104;4041.1043704345675;31836.204094897814;32176.517352239734;32478.512822307683;32604.6739532604;33473.33053338944;3240.4653232216656;3243.5459035735016;3255.3977514159956;3283.441814891474;3348.916277093068;515586;516673.4166329182;517081.41459292464;517702;518232;61633.76732465415;61979.450513736614;62041.06938395928;62105.68947155235;62116.37883621106;35565.8221708895;35834.462483062256;35866.46200306997;67156.32843671598;67250.31874203184;16355.427560035341;16450.259738311805;16465.765067619977;16467.83532164687;16496.092714900795;11375.488103035383;11635.650930472098;11644.88355116455;12571.874281257253;12650.81023784644;17362.739558906625;17508.47474575765;18085.607424851234;20285.594288114156;20343;6900.3444672756;7386.667599958015;7628.69485220596;7708.38332933364;7766.689332426639;846.9178559343903;872.9476231426007;1035.699647102347;1069.7699994501063;1069.8926610957622;31011.379772404427;31137.065888023397;31276.53085203724;31290;31737.682623173936;1064.21248276275;1077.5043479999288;1093.3658478082693;1107.4241394474827;1158.7045303447567;15752.369905203892;15926;15946.441874534568;16346.18269086541;16810.83189168117;662566;764282;772449;773734;994115;62495.375046248955;62992.68503657453;63203;63283.367166328666;63648;1359.9864001360058;1380.3167432121165;1397.5318268380206;1405.5150972914328;1407.5847624950459;31015;31707.365852682356;31852;32479;32524.674753252166;54171.458285418215;54702.179467308815;54793.630159246335;54837;54878;541033;543736;546230;546610;546818;27551.31121721973;27840.303992400353;28010.579841301987;37302;38137.42793858039;37925.62074379331;40669.3111291394;46473.53526464692;48870;54436.72781636067;32935.67064329326;41769.79115104405;42183.156336872475;43110.922226944575;43473.56526434695;40171.39742903801;40287.597124029555;40530.59469405386;41553.79223103986;41820.79089604533;37531.624683752816;38132;39363.01592460097;39445.60554394534;39482.40776388299;34103;35591.46612800758;36339.27321453503;36545;36667.44998825073;22814;24658.383540411633;25929.740702593488;26427.339316517242;26994.849445324726 21:34:58 INFO - PROCESS | 1946 | |2;jquery.html;364.6462930956971;373.7103744597936;378.75759513911095;394.3552292002575;399.75215366472776;14925.179115148663;15960;19361.709574356377;21293.828839413825;21487.818170000643;20128.49678758032;20301.49246268844;20864.374068777925;21053.368398948016;21067.157313707434;5299.920501192485;5462.562994960394;5465.617406781527;5478.780848766045;5961.254843144607;5924.703764811764;5964.701764911759;5993.970030149843;6015.849603759919;6018;3856.132370216707;4069.2268468990947;4074.694397920153;4625.421822272216;6088.756449742005;5760.884782304331;5988.790392336269;6041.124037014608;6041.942660034477;6050.546209034318;5730.9426905730825;5802.013657678205;5804.767809287623;5807.593468457209;5815.127730840365;11637.534498620045;13157;23586;23912;25263.73681315927;2180.563887222562;2418.1899063813466;2512.9120480783085;2687.583424569195;2828.9009884654142;8423.70517031907;8846.380753347205;10022.498875056219;10427.113695335906;10440;1808.782946046483;1819.763828464885;1841.3555255660547;1863.319888240796;1970.6452838489067;121139.3943030288;126547.10179347316;127229.72770272271;128030;132728;9998.80014398277;10600.932818100551;11353.602623908124;11545.711357215969;11751.529938802352;14263.78604320936;14718.116912985255;14756.262186890612;14765.704685906436;14766.335514901855;8540.615672294762;8553.786155346043;8593.484390936565;8674.479531228146;8695.347848911357;8493.575321233913;8493.745187644257;8507.53208573522;8690.565471726515;8706.825863482822;3992.9800350998057;4438.866833994985;4455.77721113943;4575.931361029588;4643.233866412072;1747.851432628241;1813.310941842118;1842.8249316315196;1908.8186622270862;1912.6461604603194;845.7082306604211;848.0586548930683;870.303756994402;929.9116583924516;945.7541039329824;19459.805401946083;20719.8964005179;20797.688034679493;21132.366029019155;21217;921.7277174561498;952.4904176265665;954.8901876284177;959.7840485890758;1009.6668099527138;10388.740281493023;10617.522211500502;10906.400147991937;10948.890511094787;10956.616518421977;574965;600919;625350;626228;629334;127571;132203.3389833064;133277.3336133313;134075.32962335125;151891.24054379656;960.577345967772;987.5950860147306;1022.1209759606733;1023.5649848814224;1031.8761748590066;14602.926985365006;19020;19234;19458.610827783647;19626.803731963068;55436;58941;60135.39864601298;60345.793084138946;60456.69771651114;76771.23228767641;79022;79245.0188745286;80140;82487;6985.650717464208;7408.296211859864;8073.555954422328;8301.875471867806;8409.747707568782;10620.923987504573;11018.944905275423;12826.67933301675;13276;13375.996800240215;11010.339379636927;11371.260868043551;11508.251963622673;11541.596044138414;11541.942290288494;11161.274517156684;11250.66248012561;11341.206115571831;11594.478248479007;11642.24325418845;11330.660080197278;11529.711757206136;11743.11926605491;11753.294802311888;11792.587259445878;8165.428420010542;8177.345812335118;8278.420510564203;8324;8360.247577718035;10922;11210.55157793679;11388.943055285003;11694.532218711496;11724.120690948384 21:34:58 INFO - PROCESS | 1946 | |3;mootools.html;1491.5972687374406;1536.0399750156153;1693.8136804951462;1848.8890666559998;1865.5149661088121;7768.57272849993;7981.76054718358;8173.673053077877;8725.694600688978;8741.431806932553;7901.920980790191;7979.202079792032;8250.628721707522;8454.957725211381;8468.872966905501;1874.9156287967005;2236.0943817753837;2261.875596842173;2262.9321120366335;2263.0495192019307;2231.8102961248314;2267.1951457232694;2297.632378819389;2298.839081264312;2304.700388949434;2068.348470231872;2128.4998025464;2275.6814046033487;2317.327974887289;2320.2575175943703;2265.0373591223665;2281.566502364554;2299.861568523583;2303.9078436862505;2313.710786151731;2291.702078729771;2291.7249930008506;2297.241910169657;2297.9425514362274;2302.5855346037706;8430.45202061864;8628.094050124771;8637.0499245083;8638;8671.566421678954;5065.316182315414;5231.476852314776;5238.973805130988;5241.768184476645;5242.292290540767;3389.9830500847584;3462.722982161421;3603.1984007996;3605.873794417209;3617.149969757105;1044.9007344302279;1046.4663021858908;1062.8458873463383;1071;1086.2504871638544;11128.0541154002;11256.943715281453;11280.097592192606;11297.548098076068;11314.490847911864;4435.645148388121;4546.227141385973;4647.209974304376;4660.790264438108;4667.533246675373;9559.952200238955;9647.469389183521;9655.903440965641;9663.758406039906;9684.903150968541;5220;5221.269022336875;5224.608154388436;5224.843254702365;5236.214567814858;4652.418447694038;4683.086798074411;4802.663813533089;4803.711777293373;4807.086653535817;3291.736661067109;3780.716446266541;3810.3522401191863;3829.885103446901;3863.30460517104;1158.629238643626;1240.49759847262;1244.7137158453606;1245.6076335954147;1255.9120861539786;787.8896954426385;872.8123453457537;892.2193081054078;909.1681111782777;953.4947373659542;4928.901421971541;5810.680412577349;5850.502707269845;5888.146218798251;5902.232709747791;904.9186222464224;906.6328137104351;920.2821798996794;924.426855349674;938.5072836760755;3515.31451366981;3887.086534664352;3894.903551676424;3989.5412027617194;4022.336314508132;10140.949295253477;10546.209034322457;10553.683389498327;10554.102901253325;10568.947155264328;8954.328425368123;10570.207234457446;10586.682399527872;10587.84118238227;10600.04599586041;7796;7903.525788452711;8022.598870056474;8045.436819422701;8049.597520124087;5626.774929002794;5641.830745077655;5731.713414329351;5816.854578635568;5839.445252700986;8684.43551169172;9520.047995200424;9591.712248632552;9755.512224388895;9900.801983960137;9654.71035868925;9869.309148359545;9992.400455972665;10004.24968127408;10008.549615267184;7038.8944165838575;7124.430045596444;7193.244709305448;7263.782086537412;7265.20082790882;7943.324817390587;8008.47944883604;8022.638981245741;8031.799205019687;8033.076196237507;7180.102487189101;7317.085364329459;7352.264773522605;7410.703571857281;7433.996410484731;7236.276372362721;7267.091613548307;7275.563466192046;7345.742898998509;7351.264873512606;6653.73385064592;6771.86456270862;6871.106756121672;6875.5874647521305;6886.070380498569;4871.5128487151005;4872.731999739906;4937.061958228069;4954.5788607968725;5055;4726.669133160645;4907.656464047483;5019.824306149268;5051.166557517969;5062.797488100435 21:34:58 INFO - PROCESS | 1946 | |4;prototype.html;294.84225939122575;298.2945334284418;298.9118210128012;310.3219465467953;313.2950860563732;4592.025429065508;5504.4770746779095;5517.282753242078;5538.227767114525;5567.86080347991;4688.695234809735;5251.711155886424;5377.274068000819;5402.729863506828;5411.053065713494;4132.297509423405;4146.274401979656;4146.336586146217;4157.708960372767;4179.707420480568;3460.394430974582;3902.921941561167;4121.9175616487655;4164.250434921728;4178.791060446981;3382.8646854125805;4081.4490043844;4102.087517862321;4113.25961326961;4120.381942708603;3454.821564160978;4059.7564146151326;4099.1801639672185;4100.692448066377;4104.548499665035;2820.520511513027;3520.612732599412;3537.557805274341;3544.3442963051916;3571.5357003589365;4582.839600613996;5006.347905191278;5303.302943058223;5307.363116426015;5318.973405132988;4669.6731228814;4690.64820138491;4713.410823647044;4713.501844022673;4721.693089949143;5016.724080175589;5137.715571107223;5335.199020514222;5493.313335833021;5735.283089613798;3855.0169706724887;3857.305684976703;3857.3442514772555;3891.5913829047827;3900.7854567998747;4388.880835386988;5241.008416801591;5872.001759700861;5875.327920049926;5890.410958904118;3420.9566082345123;3664.7984360859878;3769.189624230749;3914.784686842194;3941.921161576752;3478.6869181773764;3826.1825632824743;3914.2954268231424;3980.3631418972827;4009.719319647597;3906.7851268179957;3943.684505239574;4640.32715256286;4660.343604145604;4674.322223277673;4971.403431588233;5491.9725401373535;5752.02215623345;5787.71061446926;5799.985002624524;1197.5269768441417;1208.848893888264;1296.4490091710986;1318.2617734068951;1356.7015256643522;722.5159143373952;797.9401544884157;815.8985369750789;831.5094094484283;837.5770236030887;3995.900102497461;4073.0021144819707;4272.594103560157;4446.466424029072;4459.554044595515;4237.978810105929;4880.949406620862;4946.307516947631;4964.627652926045;4968.503149685002;3953.466282051944;4389.407429996973;4412.271975124068;4484.775761211927;4495.876030992252;2087.7390326209224;2434.3305590962627;2497.5504409206155;2598.857062861523;2628.147156254836;4477.3731677565165;4490.326451032306;4841.8547443576745;5075.187969924795;5137.867784869238;4494.73031618104;4629.490756016836;4894.853154405374;4954.5540901319055;5108.846734597968;4635.559621835921;4833;4863.781129849165;4895.853124406273;4916.812018651737;4601.608863246662;4741.122892264942;4774.498677638798;4790.712557246577;4839.854804355875;4295.785210739388;4426.822927082881;4700.788464519173;4748.667593268438;4784.784684689128;4973.35346404965;5300.6289559731995;5409.29679141709;6154.907676384768;6163.414475624809;3343.4316166252283;3749.6250374962287;3866.4586957825363;3870.4387863759034;3906.746061506107;5836.737346819318;5840.737166827588;5859.44335288147;5907.054871220584;5965.791197308247;5517.531009864046;5777.480026797609;5778.36437991829;5792.478676919098;5808.6805225711305;4601.47083085436;5069.110606954001;5161.974190129025;5292.470752924831;5504.862378440571;5460.774716822904;5678.290213723285;5683.346415162309;5698.629589076697;5725.227094342377;4883.021356972173;5455.626208184855;5767.279090113736;5778.624389414675;5792.538171277106;5664.85837854057;5675.659460432387;5682.829515114553;5691.4877661010705;5697.373288938296 21:34:58 INFO - PROCESS | 1946 | |5;yui.html;706.7208452661199;713.0265998534336;777.4557809533325;822.1737154160069;824.5382585751976;15849.04905705657;16498.83501164988;16540.751888721665;16543.09013004284;16549.669006619875;14777.03949243301;14946.252687365615;14966.476173333935;14972.625684357896;14976.326065327059;686.7253098760485;705.3581241070626;709.2765379313098;709.5529816215777;845.7335939179154;698.3958875572619;701.0640794539296;805.2028491793112;874.2961915657893;879.6349514951288;894.1773568317132;902.255639097747;903.2954295649371;905.8414777413959;909.781652403425;900.3517467423445;900.6397441023578;901.3150005995425;901.3375169250595;903.70629545398;530.9283246761715;669.5447095974736;670.9161354830646;672.4620303756975;676.1244188775498;14474.421023159166;14942.701145977127;15014.399424023026;15047.247637618075;15053.5483935481;14468.27658617065;14784.778228326584;14785.112893226333;14818.184999825005;14895.126557613003;12053.57812476559;12082.516699332104;12104.334261615608;12105.818412723816;12234.71775953032;3844.5770965193806;3861.362875125601;3898.941515877264;3923.2545816294814;4021.919561608781;120591;122056.38971805172;124141;126603;126956.36521817424;11482.18686421832;11502.141672237423;11585;11727.765444691058;11835.993940515153;23975.28074157778;24006.39984000379;24008.15971441026;24017;24031;7922.643481043367;8012.559309237931;8033.236842499953;8101.918980810235;8170.101288858221;4338.826446942087;4338.826446942087;4354.847580334673;4408.448943882015;4520.977395113004;3516.9976556681736;3646.8176591170336;3673;3674;3885.7862817545306;704.3484776581744;706.0891450029507;716.9283071692894;723.4827098624509;724.7680742162457;496.7283753590819;632.677334559378;677.993220067793;680.3706571421515;684.8561802021632;8435.704750333709;8720.8691869622;9479.715608531755;9610.66362677303;9805.901940980499;567.1549391406775;582.1849410824879;585.439802925208;630.2405601250482;666.5733930284622;4559.361689363492;4567.566081222279;4596.5863072323655;4632.351470794091;4634.976825115853;97557.8059240449;99090.5045474768;105579;153989;156988.21505892396;91816;107742.3838642421;109149.45425272823;117735.41132294283;120897.39551302363;77230;107557;109008;109274.17540386207;111272.88727112625;9392.95303523478;9741.284349042717;9816.17757186639;9818.07104368196;9821.994440757158;56717.86564268774;57965.71017145056;61754.38245617666;62712;64320.03519947298;68053.97919031317;91062;92067.53966230126;92426;96351.0364896342;10110.140638045848;11950.880491194977;13268.53560125391;13487.59537213846;13866.098703584235;15071.773923390938;15153.393864245747;15366.154861482726;15691.372345106069;15710.450134245246;14111.012229144271;14179.14925104497;14412;14417.207053611732;14851.331690074188;13532.526361577298;14105.297687387174;14160.291985400483;14182.290885455892;14695.779563306345;13629.727405452033;13919.304034798017;13953.581392558239;14364.353604087632;14520.564383068524;10682.305650132717;10689.494910121139;10910;11059.889401105886;11078.889211107786;10671.986161314662;11449.141314401286;11464.25482343645;11623.418829058344;11772 21:34:58 INFO - PROCESS | 1946 | __end_tp_report 21:34:58 INFO - PROCESS | 1946 | __start_cc_report 21:34:58 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,1251 21:34:58 INFO - PROCESS | 1946 | __end_cc_report 21:34:58 INFO - PROCESS | 1946 | __startTimestamp1470630898405__endTimestamp 21:34:58 INFO - PROCESS | 1946 | 21:34:58 INFO - PROCESS | 1946 | ------- Summary: start ------- 21:34:58 INFO - PROCESS | 1946 | Number of tests: 6 21:34:58 INFO - PROCESS | 1946 | 21:34:58 INFO - PROCESS | 1946 | [#0] dojo.html Cycles:165 Average:5821.42 Median:6074.15 stddev:652.39 (10.7%) stddev-sans-first:626.04 21:34:58 INFO - PROCESS | 1946 | Values: 3396.3 3397.2 3406.4 3428.3 3455.8 5665.1 5747.2 5773.0 5823.0 5828.5 5728.5 5802.5 5837.4 5860.5 5924.0 5218.7 5312.8 5320.6 5345.5 5377.9 5184.2 5297.9 5332.8 5401.4 5408.5 5322.6 5322.8 5335.7 5369.5 5394.7 5183.2 5286.1 5312.8 5322.2 5329.8 5177.5 5236.0 5242.1 5257.5 5334.6 5766.1 5785.5 5819.4 5834.3 5843.9 6358.2 6360.1 6360.3 6365.2 6369.5 6186.9 6248.4 6278.6 6306.5 6313.5 5893.6 5965.9 5968.6 5975.8 5985.1 6311.3 6321.3 6333.9 6353.0 6364.7 6079.9 6121.5 6125.4 6150.6 6213.7 6286.3 6317.5 6318.0 6318.3 6333.7 6283.4 6298.8 6306.5 6318.7 6329.1 6093.5 6214.2 6214.8 6296.8 6300.0 4834.1 5031.9 5048.4 5051.3 5054.9 3870.7 4088.6 4535.0 4542.8 4583.9 5867.7 5883.6 5896.5 5909.2 5933.1 6203.3 6254.2 6275.7 6289.6 6291.2 5851.0 5866.4 5873.0 5903.1 5919.7 5507.3 5507.6 5518.2 5531.7 5535.5 6241.9 6288.3 6371.1 6374.3 6460.2 6294.0 6309.7 6310.1 6321.1 6360.1 6379.6 6382.7 6390.8 6393.2 6401.3 6115.4 6167.2 6236.3 6256.1 6273.6 6241.8 6253.3 6291.7 6308.2 6308.5 6163.0 6236.8 6237.7 6241.3 6244.3 4744.9 6074.1 6117.2 6265.9 6266.0 6015.6 6058.9 6074.5 6084.8 6112.4 6193.0 6220.0 6221.6 6221.9 6233.7 5892.9 5929.9 6057.2 6064.9 6079.9 21:34:58 INFO - PROCESS | 1946 | 21:34:58 INFO - PROCESS | 1946 | [#1] ext.html Cycles:180 Average:75040.82 Median:36442.14 stddev:170136.49 (466.9%) stddev-sans-first:170521.63 21:34:58 INFO - PROCESS | 1946 | Values: 464.0 487.5 498.0 503.7 506.4 31262.8 40916.2 41093.2 41395.2 45022.3 44174.8 44824.0 44838.3 44839.3 44871.1 8627.5 9601.0 9770.7 9781.6 9782.0 8613.8 11595.2 11787.4 11813.4 12168.9 11787.2 11859.5 11864.3 11889.9 11909.5 11800.4 11855.9 11858.3 11862.7 11865.3 11600.9 11677.3 11747.4 11763.8 11768.8 40443.0 40812.0 40832.4 40859.0 41036.0 3425.4 3485.9 3558.7 3831.4 4041.1 31836.2 32176.5 32478.5 32604.7 33473.3 3240.5 3243.5 3255.4 3283.4 3348.9 515586.0 516673.4 517081.4 517702.0 518232.0 61633.8 61979.5 62041.1 62105.7 62116.4 35565.8 35834.5 35866.5 67156.3 67250.3 16355.4 16450.3 16465.8 16467.8 16496.1 11375.5 11635.7 11644.9 12571.9 12650.8 17362.7 17508.5 18085.6 20285.6 20343.0 6900.3 7386.7 7628.7 7708.4 7766.7 846.9 872.9 1035.7 1069.8 1069.9 31011.4 31137.1 31276.5 31290.0 31737.7 1064.2 1077.5 1093.4 1107.4 1158.7 15752.4 15926.0 15946.4 16346.2 16810.8 662566.0 764282.0 772449.0 773734.0 994115.0 62495.4 62992.7 63203.0 63283.4 63648.0 1360.0 1380.3 1397.5 1405.5 1407.6 31015.0 31707.4 31852.0 32479.0 32524.7 54171.5 54702.2 54793.6 54837.0 54878.0 541033.0 543736.0 546230.0 546610.0 546818.0 27551.3 27840.3 28010.6 37302.0 38137.4 37925.6 40669.3 46473.5 48870.0 54436.7 32935.7 41769.8 42183.2 43110.9 43473.6 40171.4 40287.6 40530.6 41553.8 41820.8 37531.6 38132.0 39363.0 39445.6 39482.4 34103.0 35591.5 36339.3 36545.0 36667.4 22814.0 24658.4 25929.7 26427.3 26994.8 21:34:58 INFO - PROCESS | 1946 | 21:34:58 INFO - PROCESS | 1946 | [#2] jquery.html Cycles:180 Average:35830.14 Median:13165.09 stddev:102503.15 (778.6%) stddev-sans-first:102756.10 21:34:58 INFO - PROCESS | 1946 | Values: 364.6 373.7 378.8 394.4 399.8 14925.2 15960.0 19361.7 21293.8 21487.8 20128.5 20301.5 20864.4 21053.4 21067.2 5299.9 5462.6 5465.6 5478.8 5961.3 5924.7 5964.7 5994.0 6015.8 6018.0 3856.1 4069.2 4074.7 4625.4 6088.8 5760.9 5988.8 6041.1 6041.9 6050.5 5730.9 5802.0 5804.8 5807.6 5815.1 11637.5 13157.0 23586.0 23912.0 25263.7 2180.6 2418.2 2512.9 2687.6 2828.9 8423.7 8846.4 10022.5 10427.1 10440.0 1808.8 1819.8 1841.4 1863.3 1970.6 121139.4 126547.1 127229.7 128030.0 132728.0 9998.8 10600.9 11353.6 11545.7 11751.5 14263.8 14718.1 14756.3 14765.7 14766.3 8540.6 8553.8 8593.5 8674.5 8695.3 8493.6 8493.7 8507.5 8690.6 8706.8 3993.0 4438.9 4455.8 4575.9 4643.2 1747.9 1813.3 1842.8 1908.8 1912.6 845.7 848.1 870.3 929.9 945.8 19459.8 20719.9 20797.7 21132.4 21217.0 921.7 952.5 954.9 959.8 1009.7 10388.7 10617.5 10906.4 10948.9 10956.6 574965.0 600919.0 625350.0 626228.0 629334.0 127571.0 132203.3 133277.3 134075.3 151891.2 960.6 987.6 1022.1 1023.6 1031.9 14602.9 19020.0 19234.0 19458.6 19626.8 55436.0 58941.0 60135.4 60345.8 60456.7 76771.2 79022.0 79245.0 80140.0 82487.0 6985.7 7408.3 8073.6 8301.9 8409.7 10620.9 11018.9 12826.7 13276.0 13376.0 11010.3 11371.3 11508.3 11541.6 11541.9 11161.3 11250.7 11341.2 11594.5 11642.2 11330.7 11529.7 11743.1 11753.3 11792.6 8165.4 8177.3 8278.4 8324.0 8360.2 10922.0 11210.6 11388.9 11694.5 11724.1 21:34:58 INFO - PROCESS | 1946 | 21:34:58 INFO - PROCESS | 1946 | [#3] mootools.html Cycles:180 Average:5554.04 Median:5058.90 stddev:3089.75 (61.1%) stddev-sans-first:3083.33 21:34:58 INFO - PROCESS | 1946 | Values: 1491.6 1536.0 1693.8 1848.9 1865.5 7768.6 7981.8 8173.7 8725.7 8741.4 7901.9 7979.2 8250.6 8455.0 8468.9 1874.9 2236.1 2261.9 2262.9 2263.0 2231.8 2267.2 2297.6 2298.8 2304.7 2068.3 2128.5 2275.7 2317.3 2320.3 2265.0 2281.6 2299.9 2303.9 2313.7 2291.7 2291.7 2297.2 2297.9 2302.6 8430.5 8628.1 8637.0 8638.0 8671.6 5065.3 5231.5 5239.0 5241.8 5242.3 3390.0 3462.7 3603.2 3605.9 3617.1 1044.9 1046.5 1062.8 1071.0 1086.3 11128.1 11256.9 11280.1 11297.5 11314.5 4435.6 4546.2 4647.2 4660.8 4667.5 9560.0 9647.5 9655.9 9663.8 9684.9 5220.0 5221.3 5224.6 5224.8 5236.2 4652.4 4683.1 4802.7 4803.7 4807.1 3291.7 3780.7 3810.4 3829.9 3863.3 1158.6 1240.5 1244.7 1245.6 1255.9 787.9 872.8 892.2 909.2 953.5 4928.9 5810.7 5850.5 5888.1 5902.2 904.9 906.6 920.3 924.4 938.5 3515.3 3887.1 3894.9 3989.5 4022.3 10140.9 10546.2 10553.7 10554.1 10568.9 8954.3 10570.2 10586.7 10587.8 10600.0 7796.0 7903.5 8022.6 8045.4 8049.6 5626.8 5641.8 5731.7 5816.9 5839.4 8684.4 9520.0 9591.7 9755.5 9900.8 9654.7 9869.3 9992.4 10004.2 10008.5 7038.9 7124.4 7193.2 7263.8 7265.2 7943.3 8008.5 8022.6 8031.8 8033.1 7180.1 7317.1 7352.3 7410.7 7434.0 7236.3 7267.1 7275.6 7345.7 7351.3 6653.7 6771.9 6871.1 6875.6 6886.1 4871.5 4872.7 4937.1 4954.6 5055.0 4726.7 4907.7 5019.8 5051.2 5062.8 21:34:58 INFO - PROCESS | 1946 | 21:34:58 INFO - PROCESS | 1946 | [#4] prototype.html Cycles:180 Average:4360.28 Median:4695.72 stddev:1351.11 (28.8%) stddev-sans-first:1320.00 21:34:58 INFO - PROCESS | 1946 | Values: 294.8 298.3 298.9 310.3 313.3 4592.0 5504.5 5517.3 5538.2 5567.9 4688.7 5251.7 5377.3 5402.7 5411.1 4132.3 4146.3 4146.3 4157.7 4179.7 3460.4 3902.9 4121.9 4164.3 4178.8 3382.9 4081.4 4102.1 4113.3 4120.4 3454.8 4059.8 4099.2 4100.7 4104.5 2820.5 3520.6 3537.6 3544.3 3571.5 4582.8 5006.3 5303.3 5307.4 5319.0 4669.7 4690.6 4713.4 4713.5 4721.7 5016.7 5137.7 5335.2 5493.3 5735.3 3855.0 3857.3 3857.3 3891.6 3900.8 4388.9 5241.0 5872.0 5875.3 5890.4 3421.0 3664.8 3769.2 3914.8 3941.9 3478.7 3826.2 3914.3 3980.4 4009.7 3906.8 3943.7 4640.3 4660.3 4674.3 4971.4 5492.0 5752.0 5787.7 5800.0 1197.5 1208.8 1296.4 1318.3 1356.7 722.5 797.9 815.9 831.5 837.6 3995.9 4073.0 4272.6 4446.5 4459.6 4238.0 4880.9 4946.3 4964.6 4968.5 3953.5 4389.4 4412.3 4484.8 4495.9 2087.7 2434.3 2497.6 2598.9 2628.1 4477.4 4490.3 4841.9 5075.2 5137.9 4494.7 4629.5 4894.9 4954.6 5108.8 4635.6 4833.0 4863.8 4895.9 4916.8 4601.6 4741.1 4774.5 4790.7 4839.9 4295.8 4426.8 4700.8 4748.7 4784.8 4973.4 5300.6 5409.3 6154.9 6163.4 3343.4 3749.6 3866.5 3870.4 3906.7 5836.7 5840.7 5859.4 5907.1 5965.8 5517.5 5777.5 5778.4 5792.5 5808.7 4601.5 5069.1 5162.0 5292.5 5504.9 5460.8 5678.3 5683.3 5698.6 5725.2 4883.0 5455.6 5767.3 5778.6 5792.5 5664.9 5675.7 5682.8 5691.5 5697.4 21:34:58 INFO - PROCESS | 1946 | 21:34:58 INFO - PROCESS | 1946 | [#5] yui.html Cycles:180 Average:23876.10 Median:3852.97 stddev:36759.50 (954.1%) stddev-sans-first:36821.46 21:34:58 INFO - PROCESS | 1946 | Values: 706.7 713.0 777.5 822.2 824.5 15849.0 16498.8 16540.8 16543.1 16549.7 14777.0 14946.3 14966.5 14972.6 14976.3 686.7 705.4 709.3 709.6 845.7 698.4 701.1 805.2 874.3 879.6 894.2 902.3 903.3 905.8 909.8 900.4 900.6 901.3 901.3 903.7 530.9 669.5 670.9 672.5 676.1 14474.4 14942.7 15014.4 15047.2 15053.5 14468.3 14784.8 14785.1 14818.2 14895.1 12053.6 12082.5 12104.3 12105.8 12234.7 3844.6 3861.4 3898.9 3923.3 4021.9 120591.0 122056.4 124141.0 126603.0 126956.4 11482.2 11502.1 11585.0 11727.8 11836.0 23975.3 24006.4 24008.2 24017.0 24031.0 7922.6 8012.6 8033.2 8101.9 8170.1 4338.8 4338.8 4354.8 4408.4 4521.0 3517.0 3646.8 3673.0 3674.0 3885.8 704.3 706.1 716.9 723.5 724.8 496.7 632.7 678.0 680.4 684.9 8435.7 8720.9 9479.7 9610.7 9805.9 567.2 582.2 585.4 630.2 666.6 4559.4 4567.6 4596.6 4632.4 4635.0 97557.8 99090.5 105579.0 153989.0 156988.2 91816.0 107742.4 109149.5 117735.4 120897.4 77230.0 107557.0 109008.0 109274.2 111272.9 9393.0 9741.3 9816.2 9818.1 9822.0 56717.9 57965.7 61754.4 62712.0 64320.0 68054.0 91062.0 92067.5 92426.0 96351.0 10110.1 11950.9 13268.5 13487.6 13866.1 15071.8 15153.4 15366.2 15691.4 15710.5 14111.0 14179.1 14412.0 14417.2 14851.3 13532.5 14105.3 14160.3 14182.3 14695.8 13629.7 13919.3 13953.6 14364.4 14520.6 10682.3 10689.5 10910.0 11059.9 11078.9 10672.0 11449.1 11464.3 11623.4 11772.0 21:34:58 INFO - PROCESS | 1946 | -------- Summary: end -------- 21:34:58 INFO - PROCESS | 1946 | 21:34:59 INFO - TEST-INFO | 1946: exit 0 21:34:59 INFO - TEST-OK | dromaeo_css | took 1117949ms 21:34:59 INFO - TEST-START | kraken 21:34:59 INFO - operating with platform_type : mac_ 21:34:59 INFO - Initialising browser for kraken test... 21:34:59 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp5lnmad/profile http://localhost:50743/getInfo.html) 21:35:01 INFO - PROCESS | 1949 | __metrics Screen width/height:1600/1200 21:35:01 INFO - PROCESS | 1949 | colorDepth:24 21:35:01 INFO - PROCESS | 1949 | Browser inner width/height: 1024/689 21:35:01 INFO - PROCESS | 1949 | __metrics 21:35:01 INFO - PROCESS | 1949 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:35:01 INFO - PROCESS | 1949 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:35:02 INFO - TEST-INFO | 1949: exit 0 21:35:02 INFO - Browser initialized. 21:35:02 INFO - Running cycle 1/1 for kraken test... 21:35:02 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp5lnmad/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:35:14 INFO - PROCESS | 1950 | RSS: Main: 243716096 21:35:14 INFO - PROCESS | 1950 | 21:35:16 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:50743/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50743/tests/kraken/driver.html?testName=audio-beat-detection) 21:35:17 INFO - PROCESS | 1950 | RSS: Main: 294727680 21:35:17 INFO - PROCESS | 1950 | 21:35:20 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:50743/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50743/tests/kraken/driver.html?testName=audio-dft) 21:35:21 INFO - PROCESS | 1950 | RSS: Main: 300097536 21:35:21 INFO - PROCESS | 1950 | 21:35:24 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:50743/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50743/tests/kraken/driver.html?testName=audio-fft) 21:35:25 INFO - PROCESS | 1950 | RSS: Main: 397492224 21:35:25 INFO - PROCESS | 1950 | 21:35:27 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:50743/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50743/tests/kraken/driver.html?testName=audio-oscillator) 21:35:27 INFO - PROCESS | 1950 | RSS: Main: 299884544 21:35:27 INFO - PROCESS | 1950 | 21:35:29 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:50743/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50743/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:35:30 INFO - PROCESS | 1950 | RSS: Main: 304902144 21:35:30 INFO - PROCESS | 1950 | 21:35:33 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:50743/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50743/tests/kraken/driver.html?testName=imaging-darkroom) 21:35:33 INFO - PROCESS | 1950 | RSS: Main: 341102592 21:35:33 INFO - PROCESS | 1950 | 21:35:36 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:50743/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50743/tests/kraken/driver.html?testName=imaging-desaturate) 21:35:37 INFO - PROCESS | 1950 | RSS: Main: 341553152 21:35:37 INFO - PROCESS | 1950 | 21:35:40 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:50743/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50743/tests/kraken/driver.html?testName=json-parse-financial) 21:35:41 INFO - PROCESS | 1950 | RSS: Main: 340426752 21:35:41 INFO - PROCESS | 1950 | 21:35:42 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:50743/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50743/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:35:43 INFO - PROCESS | 1950 | RSS: Main: 316395520 21:35:43 INFO - PROCESS | 1950 | 21:35:45 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:50743/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50743/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:35:45 INFO - PROCESS | 1950 | RSS: Main: 322297856 21:35:45 INFO - PROCESS | 1950 | 21:35:47 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:50743/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50743/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:35:48 INFO - PROCESS | 1950 | RSS: Main: 327999488 21:35:48 INFO - PROCESS | 1950 | 21:35:51 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:50743/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50743/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:35:52 INFO - PROCESS | 1950 | RSS: Main: 330850304 21:35:52 INFO - PROCESS | 1950 | 21:35:55 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:50743/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50743/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:35:55 INFO - PROCESS | 1950 | RSS: Main: 332242944 21:35:55 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:50743/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50743/tests/kraken/driver.html?testName=ai-astar) 21:35:57 INFO - PROCESS | 1950 | RSS: Main: 375959552 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | __start_tp_report 21:35:57 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 21:35:57 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 21:35:57 INFO - PROCESS | 1950 | |i|pagename|runs| 21:35:57 INFO - PROCESS | 1950 | |0;ai-astar;101;113;112;113;112;114;113;113;113;113 21:35:57 INFO - PROCESS | 1950 | |1;audio-beat-detection;143;115;107;164;108;107;135;130;175;137 21:35:57 INFO - PROCESS | 1950 | |2;audio-dft;185;186;186;186;184;181;180;184;182;184 21:35:57 INFO - PROCESS | 1950 | |3;audio-fft;80;57;59;110;84;68;66;64;82;58 21:35:57 INFO - PROCESS | 1950 | |4;audio-oscillator;85;86;91;71;71;67;96;70;67;101 21:35:57 INFO - PROCESS | 1950 | |5;imaging-gaussian-blur;90;87;90;88;91;87;90;87;89;87 21:35:57 INFO - PROCESS | 1950 | |6;imaging-darkroom;95;95;95;94;95;94;95;94;95;94 21:35:57 INFO - PROCESS | 1950 | |7;imaging-desaturate;84;84;83;87;84;118;134;131;83;83 21:35:57 INFO - PROCESS | 1950 | |8;json-parse-financial;57;55;55;54;52;53;54;54;52;55 21:35:57 INFO - PROCESS | 1950 | |9;json-stringify-tinderbox;68;75;76;79;74;71;72;70;71;72 21:35:57 INFO - PROCESS | 1950 | |10;stanford-crypto-aes;85;83;85;83;86;84;86;83;86;81 21:35:57 INFO - PROCESS | 1950 | |11;stanford-crypto-ccm;139;137;138;137;142;142;146;133;156;142 21:35:57 INFO - PROCESS | 1950 | |12;stanford-crypto-pbkdf2;164;166;165;164;169;222;220;165;165;162 21:35:57 INFO - PROCESS | 1950 | |13;stanford-crypto-sha256-iterative;67;67;67;67;66;66;66;67;66;66 21:35:57 INFO - PROCESS | 1950 | __end_tp_report 21:35:57 INFO - PROCESS | 1950 | __start_cc_report 21:35:57 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,2120 21:35:57 INFO - PROCESS | 1950 | __end_cc_report 21:35:57 INFO - PROCESS | 1950 | __startTimestamp1470630957678__endTimestamp 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | ------- Summary: start ------- 21:35:57 INFO - PROCESS | 1950 | Number of tests: 14 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.80 (3.4%) stddev-sans-first:0.60 21:35:57 INFO - PROCESS | 1950 | Values: 101.0 113.0 112.0 113.0 112.0 114.0 113.0 113.0 113.0 113.0 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | [#1] audio-beat-detection Cycles:10 Average:132.10 Median:136.00 stddev:23.91 (17.6%) stddev-sans-first:25.04 21:35:57 INFO - PROCESS | 1950 | Values: 143.0 115.0 107.0 164.0 108.0 107.0 135.0 130.0 175.0 137.0 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.50 stddev:2.15 (1.2%) stddev-sans-first:2.24 21:35:57 INFO - PROCESS | 1950 | Values: 185.0 186.0 186.0 186.0 184.0 181.0 180.0 184.0 182.0 184.0 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | [#3] audio-fft Cycles:10 Average:72.80 Median:67.00 stddev:16.50 (24.6%) stddev-sans-first:17.30 21:35:57 INFO - PROCESS | 1950 | Values: 80.0 57.0 59.0 110.0 84.0 68.0 66.0 64.0 82.0 58.0 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:78.00 stddev:12.81 (16.4%) stddev-sans-first:13.48 21:35:57 INFO - PROCESS | 1950 | Values: 85.0 86.0 91.0 71.0 71.0 67.0 96.0 70.0 67.0 101.0 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:89.50 stddev:1.58 (1.8%) stddev-sans-first:1.59 21:35:57 INFO - PROCESS | 1950 | Values: 90.0 87.0 90.0 88.0 91.0 87.0 90.0 87.0 89.0 87.0 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 21:35:57 INFO - PROCESS | 1950 | Values: 95.0 95.0 95.0 94.0 95.0 94.0 95.0 94.0 95.0 94.0 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:83.50 stddev:21.50 (25.8%) stddev-sans-first:22.28 21:35:57 INFO - PROCESS | 1950 | Values: 84.0 84.0 83.0 87.0 84.0 118.0 134.0 131.0 83.0 83.0 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.50 stddev:1.52 (2.8%) stddev-sans-first:1.20 21:35:57 INFO - PROCESS | 1950 | Values: 57.0 55.0 55.0 54.0 52.0 53.0 54.0 54.0 52.0 55.0 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | [#9] json-stringify-tinderbox Cycles:10 Average:72.80 Median:73.00 stddev:3.22 (4.4%) stddev-sans-first:2.92 21:35:57 INFO - PROCESS | 1950 | Values: 68.0 75.0 76.0 79.0 74.0 71.0 72.0 70.0 71.0 72.0 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:85.00 stddev:1.69 (2.0%) stddev-sans-first:1.76 21:35:57 INFO - PROCESS | 1950 | Values: 85.0 83.0 85.0 83.0 86.0 84.0 86.0 83.0 86.0 81.0 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | [#11] stanford-crypto-ccm Cycles:10 Average:141.20 Median:142.00 stddev:6.34 (4.5%) stddev-sans-first:6.67 21:35:57 INFO - PROCESS | 1950 | Values: 139.0 137.0 138.0 137.0 142.0 142.0 146.0 133.0 156.0 142.0 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:165.50 stddev:23.68 (14.3%) stddev-sans-first:24.70 21:35:57 INFO - PROCESS | 1950 | Values: 164.0 166.0 165.0 164.0 169.0 222.0 220.0 165.0 165.0 162.0 21:35:57 INFO - PROCESS | 1950 | 21:35:57 INFO - PROCESS | 1950 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 21:35:57 INFO - PROCESS | 1950 | Values: 67.0 67.0 67.0 67.0 66.0 66.0 66.0 67.0 66.0 66.0 21:35:57 INFO - PROCESS | 1950 | -------- Summary: end -------- 21:35:57 INFO - PROCESS | 1950 | 21:35:58 INFO - TEST-INFO | 1950: exit 0 21:35:58 INFO - TEST-OK | kraken | took 59534ms 21:35:58 INFO - SUITE-END | took 1177s 21:35:58 INFO - Completed test suite (00:19:37) 21:35:58 INFO - javascript benchmark 21:35:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3396.269801992571, 3397.1846756778373, 3406.369821583006, 3428.314337132574, 3455.8272086395673, 5665.12190610456, 5747.224124743158, 5772.971135144329, 5822.970885145573, 5828.475437210655, 5728.455796699317, 5802.506786923117, 5837.387074357184, 5860.50185734212, 5923.992921203395, 5218.739063046852, 5312.760925758331, 5320.6275560710765, 5345.545628621552, 5377.946220537784, 5184.2223666450145, 5297.867553311179, 5332.760025798829, 5401.351837779453, 5408.4591540845995, 5322.627416080856, 5322.813701520428, 5335.679859208441, 5369.489898459659, 5394.676319420847, 5183.170692689189, 5286.101362768339, 5312.76092575835, 5322.228276899867, 5329.840104796862, 5177.534021938043, 5235.973820130875, 5242.135047717122, 5257.4742525747115, 5334.599905007101, 5766.135079738031, 5785.537157027427, 5819.388964158787, 5834.329052159013, 5843.941560584382, 6358.1734374531015, 6360.141380913564, 6360.332165122688, 6365.1725275714325, 6369.522285828535, 6186.938130618726, 6248.3751624837605, 6278.623282603013, 6306.527010474233, 6313.463355614778, 5893.5874488785375, 5965.850853728692, 5968.641881487125, 5975.790847320323, 5985.072313791369, 6311.274203466568, 6321.304656487782, 6333.90499142504, 6353.0, 6364.681765911686, 6079.939200608026, 6121.452973575903, 6125.41808528198, 6150.630962142286, 6213.720382582795, 6286.277078135981, 6317.4630156437215, 6318.020706790455, 6318.273398559132, 6333.683315834282, 6283.37166283368, 6298.842528936813, 6306.49548036156, 6318.684065796691, 6329.082283068929, 6093.542984276197, 6214.1921550198185, 6214.844628884314, 6296.8425789354715, 6299.96850015747, 4834.057358814997, 5031.899362012739, 5048.394192696826, 5051.26756620288, 5054.949450505522, 3870.748401353903, 4088.591140885947, 4535.024969631545, 4542.772861356919, 4583.862484125481, 5867.735951882176, 5883.61756485827, 5896.528277737771, 5909.202257695242, 5933.110033495011, 6203.348648391854, 6254.15568898202, 6275.732938658134, 6289.591176573508, 6291.182146320949, 5851.0, 5866.354700982888, 5873.0, 5903.114532820111, 5919.733611987384, 5507.284053073075, 5507.642003269774, 5518.227448157261, 5531.723413829292, 5535.529479994165, 6241.906371404523, 6288.339724328879, 6371.139896113966, 6374.330695277022, 6460.160179176677, 6294.0, 6309.684515774101, 6310.148130002574, 6321.0834429008455, 6360.109584658244, 6379.649119298298, 6382.712777925098, 6390.7763228286785, 6393.2328120625825, 6401.295857455583, 6115.3578874217565, 6167.229096362955, 6236.34518375564, 6256.124142619946, 6273.560850740587, 6241.750329986749, 6253.280872699698, 6291.716872740829, 6308.179936608211, 6308.495320374451, 6163.044728067157, 6236.750529978932, 6237.688115594293, 6241.344658810941, 6244.344343843831, 4744.857654270378, 6074.149619053248, 6117.218487018656, 6265.906011409924, 6265.96867015662, 6015.639061656315, 6058.923001393399, 6074.466017966408, 6084.762715153553, 6112.419320164578, 6192.97815860378, 6219.973704338733, 6221.564490485623, 6221.906671399842, 6233.657148856856, 5892.9116063258225, 5929.940700592939, 6057.21256236704, 6064.909026364519, 6079.878402432015], "value": 5779.2092994591985, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.96520260980424, 487.4516169309527, 498.01890276156007, 503.6677986725112, 506.35439814236844, 31262.84368578157, 40916.18167636649, 41093.17813643725, 41395.17209655809, 45022.32466513005, 44174.779126104404, 44824.0, 44838.327425088566, 44839.327410088874, 44871.10257794842, 8627.525486098262, 9600.991895850957, 9770.706878793648, 9781.608735650565, 9781.951090244575, 8613.784655383633, 11595.188336816425, 11787.410629468535, 11813.409329533531, 12168.878311216908, 11787.17489775716, 11859.466324015397, 11864.347460889647, 11889.940550297237, 11909.523619055228, 11800.40997950099, 11855.881441185651, 11858.28850268978, 11862.703432414173, 11865.288082714978, 11600.88399116015, 11677.29936203822, 11747.412629368582, 11763.764724705543, 11768.823467647991, 40443.0, 40812.0, 40832.387514187314, 40859.0, 41036.0, 3425.4348032574594, 3485.9128521786906, 3558.679718825318, 3831.444440556104, 4041.1043704345675, 31836.204094897814, 32176.517352239734, 32478.512822307683, 32604.6739532604, 33473.33053338944, 3240.4653232216656, 3243.5459035735016, 3255.3977514159956, 3283.441814891474, 3348.916277093068, 515586.0, 516673.4166329182, 517081.41459292464, 517702.0, 518232.0, 61633.76732465415, 61979.450513736614, 62041.06938395928, 62105.68947155235, 62116.37883621106, 35565.8221708895, 35834.462483062256, 35866.46200306997, 67156.32843671598, 67250.31874203184, 16355.427560035341, 16450.259738311805, 16465.765067619977, 16467.83532164687, 16496.092714900795, 11375.488103035383, 11635.650930472098, 11644.88355116455, 12571.874281257253, 12650.81023784644, 17362.739558906625, 17508.47474575765, 18085.607424851234, 20285.594288114156, 20343.0, 6900.3444672756, 7386.667599958015, 7628.69485220596, 7708.38332933364, 7766.689332426639, 846.9178559343903, 872.9476231426007, 1035.699647102347, 1069.7699994501063, 1069.8926610957622, 31011.379772404427, 31137.065888023397, 31276.53085203724, 31290.0, 31737.682623173936, 1064.21248276275, 1077.5043479999288, 1093.3658478082693, 1107.4241394474827, 1158.7045303447567, 15752.369905203892, 15926.0, 15946.441874534568, 16346.18269086541, 16810.83189168117, 662566.0, 764282.0, 772449.0, 773734.0, 994115.0, 62495.375046248955, 62992.68503657453, 63203.0, 63283.367166328666, 63648.0, 1359.9864001360058, 1380.3167432121165, 1397.5318268380206, 1405.5150972914328, 1407.5847624950459, 31015.0, 31707.365852682356, 31852.0, 32479.0, 32524.674753252166, 54171.458285418215, 54702.179467308815, 54793.630159246335, 54837.0, 54878.0, 541033.0, 543736.0, 546230.0, 546610.0, 546818.0, 27551.31121721973, 27840.303992400353, 28010.579841301987, 37302.0, 38137.42793858039, 37925.62074379331, 40669.3111291394, 46473.53526464692, 48870.0, 54436.72781636067, 32935.67064329326, 41769.79115104405, 42183.156336872475, 43110.922226944575, 43473.56526434695, 40171.39742903801, 40287.597124029555, 40530.59469405386, 41553.79223103986, 41820.79089604533, 37531.624683752816, 38132.0, 39363.01592460097, 39445.60554394534, 39482.40776388299, 34103.0, 35591.46612800758, 36339.27321453503, 36545.0, 36667.44998825073, 22814.0, 24658.383540411633, 25929.740702593488, 26427.339316517242, 26994.849445324726], "value": 20878.674878031845, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.6462930956971, 373.7103744597936, 378.75759513911095, 394.3552292002575, 399.75215366472776, 14925.179115148663, 15960.0, 19361.709574356377, 21293.828839413825, 21487.818170000643, 20128.49678758032, 20301.49246268844, 20864.374068777925, 21053.368398948016, 21067.157313707434, 5299.920501192485, 5462.562994960394, 5465.617406781527, 5478.780848766045, 5961.254843144607, 5924.703764811764, 5964.701764911759, 5993.970030149843, 6015.849603759919, 6018.0, 3856.132370216707, 4069.2268468990947, 4074.694397920153, 4625.421822272216, 6088.756449742005, 5760.884782304331, 5988.790392336269, 6041.124037014608, 6041.942660034477, 6050.546209034318, 5730.9426905730825, 5802.013657678205, 5804.767809287623, 5807.593468457209, 5815.127730840365, 11637.534498620045, 13157.0, 23586.0, 23912.0, 25263.73681315927, 2180.563887222562, 2418.1899063813466, 2512.9120480783085, 2687.583424569195, 2828.9009884654142, 8423.70517031907, 8846.380753347205, 10022.498875056219, 10427.113695335906, 10440.0, 1808.782946046483, 1819.763828464885, 1841.3555255660547, 1863.319888240796, 1970.6452838489067, 121139.3943030288, 126547.10179347316, 127229.72770272271, 128030.0, 132728.0, 9998.80014398277, 10600.932818100551, 11353.602623908124, 11545.711357215969, 11751.529938802352, 14263.78604320936, 14718.116912985255, 14756.262186890612, 14765.704685906436, 14766.335514901855, 8540.615672294762, 8553.786155346043, 8593.484390936565, 8674.479531228146, 8695.347848911357, 8493.575321233913, 8493.745187644257, 8507.53208573522, 8690.565471726515, 8706.825863482822, 3992.9800350998057, 4438.866833994985, 4455.77721113943, 4575.931361029588, 4643.233866412072, 1747.851432628241, 1813.310941842118, 1842.8249316315196, 1908.8186622270862, 1912.6461604603194, 845.7082306604211, 848.0586548930683, 870.303756994402, 929.9116583924516, 945.7541039329824, 19459.805401946083, 20719.8964005179, 20797.688034679493, 21132.366029019155, 21217.0, 921.7277174561498, 952.4904176265665, 954.8901876284177, 959.7840485890758, 1009.6668099527138, 10388.740281493023, 10617.522211500502, 10906.400147991937, 10948.890511094787, 10956.616518421977, 574965.0, 600919.0, 625350.0, 626228.0, 629334.0, 127571.0, 132203.3389833064, 133277.3336133313, 134075.32962335125, 151891.24054379656, 960.577345967772, 987.5950860147306, 1022.1209759606733, 1023.5649848814224, 1031.8761748590066, 14602.926985365006, 19020.0, 19234.0, 19458.610827783647, 19626.803731963068, 55436.0, 58941.0, 60135.39864601298, 60345.793084138946, 60456.69771651114, 76771.23228767641, 79022.0, 79245.0188745286, 80140.0, 82487.0, 6985.650717464208, 7408.296211859864, 8073.555954422328, 8301.875471867806, 8409.747707568782, 10620.923987504573, 11018.944905275423, 12826.67933301675, 13276.0, 13375.996800240215, 11010.339379636927, 11371.260868043551, 11508.251963622673, 11541.596044138414, 11541.942290288494, 11161.274517156684, 11250.66248012561, 11341.206115571831, 11594.478248479007, 11642.24325418845, 11330.660080197278, 11529.711757206136, 11743.11926605491, 11753.294802311888, 11792.587259445878, 8165.428420010542, 8177.345812335118, 8278.420510564203, 8324.0, 8360.247577718035, 10922.0, 11210.55157793679, 11388.943055285003, 11694.532218711496, 11724.120690948384], "value": 9498.77163345964, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1491.5972687374406, 1536.0399750156153, 1693.8136804951462, 1848.8890666559998, 1865.5149661088121, 7768.57272849993, 7981.76054718358, 8173.673053077877, 8725.694600688978, 8741.431806932553, 7901.920980790191, 7979.202079792032, 8250.628721707522, 8454.957725211381, 8468.872966905501, 1874.9156287967005, 2236.0943817753837, 2261.875596842173, 2262.9321120366335, 2263.0495192019307, 2231.8102961248314, 2267.1951457232694, 2297.632378819389, 2298.839081264312, 2304.700388949434, 2068.348470231872, 2128.4998025464, 2275.6814046033487, 2317.327974887289, 2320.2575175943703, 2265.0373591223665, 2281.566502364554, 2299.861568523583, 2303.9078436862505, 2313.710786151731, 2291.702078729771, 2291.7249930008506, 2297.241910169657, 2297.9425514362274, 2302.5855346037706, 8430.45202061864, 8628.094050124771, 8637.0499245083, 8638.0, 8671.566421678954, 5065.316182315414, 5231.476852314776, 5238.973805130988, 5241.768184476645, 5242.292290540767, 3389.9830500847584, 3462.722982161421, 3603.1984007996, 3605.873794417209, 3617.149969757105, 1044.9007344302279, 1046.4663021858908, 1062.8458873463383, 1071.0, 1086.2504871638544, 11128.0541154002, 11256.943715281453, 11280.097592192606, 11297.548098076068, 11314.490847911864, 4435.645148388121, 4546.227141385973, 4647.209974304376, 4660.790264438108, 4667.533246675373, 9559.952200238955, 9647.469389183521, 9655.903440965641, 9663.758406039906, 9684.903150968541, 5220.0, 5221.269022336875, 5224.608154388436, 5224.843254702365, 5236.214567814858, 4652.418447694038, 4683.086798074411, 4802.663813533089, 4803.711777293373, 4807.086653535817, 3291.736661067109, 3780.716446266541, 3810.3522401191863, 3829.885103446901, 3863.30460517104, 1158.629238643626, 1240.49759847262, 1244.7137158453606, 1245.6076335954147, 1255.9120861539786, 787.8896954426385, 872.8123453457537, 892.2193081054078, 909.1681111782777, 953.4947373659542, 4928.901421971541, 5810.680412577349, 5850.502707269845, 5888.146218798251, 5902.232709747791, 904.9186222464224, 906.6328137104351, 920.2821798996794, 924.426855349674, 938.5072836760755, 3515.31451366981, 3887.086534664352, 3894.903551676424, 3989.5412027617194, 4022.336314508132, 10140.949295253477, 10546.209034322457, 10553.683389498327, 10554.102901253325, 10568.947155264328, 8954.328425368123, 10570.207234457446, 10586.682399527872, 10587.84118238227, 10600.04599586041, 7796.0, 7903.525788452711, 8022.598870056474, 8045.436819422701, 8049.597520124087, 5626.774929002794, 5641.830745077655, 5731.713414329351, 5816.854578635568, 5839.445252700986, 8684.43551169172, 9520.047995200424, 9591.712248632552, 9755.512224388895, 9900.801983960137, 9654.71035868925, 9869.309148359545, 9992.400455972665, 10004.24968127408, 10008.549615267184, 7038.8944165838575, 7124.430045596444, 7193.244709305448, 7263.782086537412, 7265.20082790882, 7943.324817390587, 8008.47944883604, 8022.638981245741, 8031.799205019687, 8033.076196237507, 7180.102487189101, 7317.085364329459, 7352.264773522605, 7410.703571857281, 7433.996410484731, 7236.276372362721, 7267.091613548307, 7275.563466192046, 7345.742898998509, 7351.264873512606, 6653.73385064592, 6771.86456270862, 6871.106756121672, 6875.5874647521305, 6886.070380498569, 4871.5128487151005, 4872.731999739906, 4937.061958228069, 4954.5788607968725, 5055.0, 4726.669133160645, 4907.656464047483, 5019.824306149268, 5051.166557517969, 5062.797488100435], "value": 4474.404757267639, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.84225939122575, 298.2945334284418, 298.9118210128012, 310.3219465467953, 313.2950860563732, 4592.025429065508, 5504.4770746779095, 5517.282753242078, 5538.227767114525, 5567.86080347991, 4688.695234809735, 5251.711155886424, 5377.274068000819, 5402.729863506828, 5411.053065713494, 4132.297509423405, 4146.274401979656, 4146.336586146217, 4157.708960372767, 4179.707420480568, 3460.394430974582, 3902.921941561167, 4121.9175616487655, 4164.250434921728, 4178.791060446981, 3382.8646854125805, 4081.4490043844, 4102.087517862321, 4113.25961326961, 4120.381942708603, 3454.821564160978, 4059.7564146151326, 4099.1801639672185, 4100.692448066377, 4104.548499665035, 2820.520511513027, 3520.612732599412, 3537.557805274341, 3544.3442963051916, 3571.5357003589365, 4582.839600613996, 5006.347905191278, 5303.302943058223, 5307.363116426015, 5318.973405132988, 4669.6731228814, 4690.64820138491, 4713.410823647044, 4713.501844022673, 4721.693089949143, 5016.724080175589, 5137.715571107223, 5335.199020514222, 5493.313335833021, 5735.283089613798, 3855.0169706724887, 3857.305684976703, 3857.3442514772555, 3891.5913829047827, 3900.7854567998747, 4388.880835386988, 5241.008416801591, 5872.001759700861, 5875.327920049926, 5890.410958904118, 3420.9566082345123, 3664.7984360859878, 3769.189624230749, 3914.784686842194, 3941.921161576752, 3478.6869181773764, 3826.1825632824743, 3914.2954268231424, 3980.3631418972827, 4009.719319647597, 3906.7851268179957, 3943.684505239574, 4640.32715256286, 4660.343604145604, 4674.322223277673, 4971.403431588233, 5491.9725401373535, 5752.02215623345, 5787.71061446926, 5799.985002624524, 1197.5269768441417, 1208.848893888264, 1296.4490091710986, 1318.2617734068951, 1356.7015256643522, 722.5159143373952, 797.9401544884157, 815.8985369750789, 831.5094094484283, 837.5770236030887, 3995.900102497461, 4073.0021144819707, 4272.594103560157, 4446.466424029072, 4459.554044595515, 4237.978810105929, 4880.949406620862, 4946.307516947631, 4964.627652926045, 4968.503149685002, 3953.466282051944, 4389.407429996973, 4412.271975124068, 4484.775761211927, 4495.876030992252, 2087.7390326209224, 2434.3305590962627, 2497.5504409206155, 2598.857062861523, 2628.147156254836, 4477.3731677565165, 4490.326451032306, 4841.8547443576745, 5075.187969924795, 5137.867784869238, 4494.73031618104, 4629.490756016836, 4894.853154405374, 4954.5540901319055, 5108.846734597968, 4635.559621835921, 4833.0, 4863.781129849165, 4895.853124406273, 4916.812018651737, 4601.608863246662, 4741.122892264942, 4774.498677638798, 4790.712557246577, 4839.854804355875, 4295.785210739388, 4426.822927082881, 4700.788464519173, 4748.667593268438, 4784.784684689128, 4973.35346404965, 5300.6289559731995, 5409.29679141709, 6154.907676384768, 6163.414475624809, 3343.4316166252283, 3749.6250374962287, 3866.4586957825363, 3870.4387863759034, 3906.746061506107, 5836.737346819318, 5840.737166827588, 5859.44335288147, 5907.054871220584, 5965.791197308247, 5517.531009864046, 5777.480026797609, 5778.36437991829, 5792.478676919098, 5808.6805225711305, 4601.47083085436, 5069.110606954001, 5161.974190129025, 5292.470752924831, 5504.862378440571, 5460.774716822904, 5678.290213723285, 5683.346415162309, 5698.629589076697, 5725.227094342377, 4883.021356972173, 5455.626208184855, 5767.279090113736, 5778.624389414675, 5792.538171277106, 5664.85837854057, 5675.659460432387, 5682.829515114553, 5691.4877661010705, 5697.373288938296], "value": 3928.173534816501, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.7208452661199, 713.0265998534336, 777.4557809533325, 822.1737154160069, 824.5382585751976, 15849.04905705657, 16498.83501164988, 16540.751888721665, 16543.09013004284, 16549.669006619875, 14777.03949243301, 14946.252687365615, 14966.476173333935, 14972.625684357896, 14976.326065327059, 686.7253098760485, 705.3581241070626, 709.2765379313098, 709.5529816215777, 845.7335939179154, 698.3958875572619, 701.0640794539296, 805.2028491793112, 874.2961915657893, 879.6349514951288, 894.1773568317132, 902.255639097747, 903.2954295649371, 905.8414777413959, 909.781652403425, 900.3517467423445, 900.6397441023578, 901.3150005995425, 901.3375169250595, 903.70629545398, 530.9283246761715, 669.5447095974736, 670.9161354830646, 672.4620303756975, 676.1244188775498, 14474.421023159166, 14942.701145977127, 15014.399424023026, 15047.247637618075, 15053.5483935481, 14468.27658617065, 14784.778228326584, 14785.112893226333, 14818.184999825005, 14895.126557613003, 12053.57812476559, 12082.516699332104, 12104.334261615608, 12105.818412723816, 12234.71775953032, 3844.5770965193806, 3861.362875125601, 3898.941515877264, 3923.2545816294814, 4021.919561608781, 120591.0, 122056.38971805172, 124141.0, 126603.0, 126956.36521817424, 11482.18686421832, 11502.141672237423, 11585.0, 11727.765444691058, 11835.993940515153, 23975.28074157778, 24006.39984000379, 24008.15971441026, 24017.0, 24031.0, 7922.643481043367, 8012.559309237931, 8033.236842499953, 8101.918980810235, 8170.101288858221, 4338.826446942087, 4338.826446942087, 4354.847580334673, 4408.448943882015, 4520.977395113004, 3516.9976556681736, 3646.8176591170336, 3673.0, 3674.0, 3885.7862817545306, 704.3484776581744, 706.0891450029507, 716.9283071692894, 723.4827098624509, 724.7680742162457, 496.7283753590819, 632.677334559378, 677.993220067793, 680.3706571421515, 684.8561802021632, 8435.704750333709, 8720.8691869622, 9479.715608531755, 9610.66362677303, 9805.901940980499, 567.1549391406775, 582.1849410824879, 585.439802925208, 630.2405601250482, 666.5733930284622, 4559.361689363492, 4567.566081222279, 4596.5863072323655, 4632.351470794091, 4634.976825115853, 97557.8059240449, 99090.5045474768, 105579.0, 153989.0, 156988.21505892396, 91816.0, 107742.3838642421, 109149.45425272823, 117735.41132294283, 120897.39551302363, 77230.0, 107557.0, 109008.0, 109274.17540386207, 111272.88727112625, 9392.95303523478, 9741.284349042717, 9816.17757186639, 9818.07104368196, 9821.994440757158, 56717.86564268774, 57965.71017145056, 61754.38245617666, 62712.0, 64320.03519947298, 68053.97919031317, 91062.0, 92067.53966230126, 92426.0, 96351.0364896342, 10110.140638045848, 11950.880491194977, 13268.53560125391, 13487.59537213846, 13866.098703584235, 15071.773923390938, 15153.393864245747, 15366.154861482726, 15691.372345106069, 15710.450134245246, 14111.012229144271, 14179.14925104497, 14412.0, 14417.207053611732, 14851.331690074188, 13532.526361577298, 14105.297687387174, 14160.291985400483, 14182.290885455892, 14695.779563306345, 13629.727405452033, 13919.304034798017, 13953.581392558239, 14364.353604087632, 14520.564383068524, 10682.305650132717, 10689.494910121139, 10910.0, 11059.889401105886, 11078.889211107786, 10671.986161314662, 11449.141314401286, 11464.25482343645, 11623.418829058344, 11772.0], "value": 7837.566793502575, "unit": "score"}], "name": "dromaeo_css", "value": 7351.896322872943, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 112.0, 113.0, 112.0, 114.0, 113.0, 113.0, 113.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 115.0, 107.0, 164.0, 108.0, 107.0, 135.0, 130.0, 175.0, 137.0], "value": 132.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 186.0, 186.0, 184.0, 181.0, 180.0, 184.0, 182.0, 184.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 59.0, 110.0, 84.0, 68.0, 66.0, 64.0, 82.0, 58.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 91.0, 71.0, 71.0, 67.0, 96.0, 70.0, 67.0, 101.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 88.0, 91.0, 87.0, 90.0, 87.0, 89.0, 87.0], "value": 88.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 87.0, 84.0, 118.0, 134.0, 131.0, 83.0, 83.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 54.0, 52.0, 53.0, 54.0, 54.0, 52.0, 55.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 76.0, 79.0, 74.0, 71.0, 72.0, 70.0, 71.0, 72.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 85.0, 83.0, 86.0, 84.0, 86.0, 83.0, 86.0, 81.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 137.0, 138.0, 137.0, 142.0, 142.0, 146.0, 133.0, 156.0, 142.0], "value": 141.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 165.0, 164.0, 169.0, 222.0, 220.0, 165.0, 165.0, 162.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 66.0, 66.0, 66.0, 67.0, 66.0, 66.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1456.2000000000003, "alertThreshold": 2.0}]} 21:35:58 INFO - Return code: 0 21:35:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:35:58 INFO - # TBPL SUCCESS # 21:35:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:35:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:35:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:35:58 INFO - 2016-08-07 21:35:58.976 screenresolution[1954:17981] starting screenresolution argv=screenresolution get 21:35:58 INFO - 2016-08-07 21:35:58.979 screenresolution[1954:17981] Display 0: 1600x1200x32@60 21:35:58 INFO - 2016-08-07 21:35:58.985 screenresolution[1955:17984] starting screenresolution argv=screenresolution list 21:35:58 INFO - Available Modes on Display 0 21:35:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:35:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:35:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:35:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:35:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:35:59 INFO - Intel Iris: 21:35:59 INFO - Chipset Model: Intel Iris 21:35:59 INFO - Type: GPU 21:35:59 INFO - Bus: Built-In 21:35:59 INFO - VRAM (Dynamic, Max): 1536 MB 21:35:59 INFO - Vendor: Intel (0x8086) 21:35:59 INFO - Device ID: 0x0a2e 21:35:59 INFO - Revision ID: 0x0009 21:35:59 INFO - Displays: 21:35:59 INFO - Display: 21:35:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:35:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:35:59 INFO - Display Serial Number: 1600x1200 60 21:35:59 INFO - Main Display: Yes 21:35:59 INFO - Mirror: Off 21:35:59 INFO - Online: Yes 21:35:59 INFO - Rotation: Supported 21:35:59 INFO - Return code: 0 21:35:59 INFO - Running post-action listener: _resource_record_post_action 21:35:59 INFO - [mozharness: 2016-08-08 04:35:59.219871Z] Finished run-tests step (success) 21:35:59 INFO - Running post-run listener: _resource_record_post_run 21:35:59 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 507904; Write bytes: 705486336; Read time: 7; Write time: 1925 21:35:59 INFO - TinderboxPrint: CPU usage
53.5% 21:35:59 INFO - TinderboxPrint: I/O read bytes / time
507,904 / 7 21:35:59 INFO - TinderboxPrint: I/O write bytes / time
705,486,336 / 1,925 21:35:59 INFO - TinderboxPrint: CPU idle
2,252.6 (46.4%) 21:35:59 INFO - TinderboxPrint: CPU system
967.4 (19.9%) 21:35:59 INFO - TinderboxPrint: CPU user
1,629.6 (33.6%) 21:35:59 INFO - TinderboxPrint: Swap in / out
407,093,248 / 0 21:35:59 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 394113536; Write bytes: 389981696; Read time: 29455; Write time: 751 21:35:59 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 507904; Write bytes: 294377472; Read time: 7; Write time: 1129 21:35:59 INFO - Running post-run listener: _upload_blobber_files 21:35:59 INFO - Blob upload gear active. 21:35:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:35:59 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 21:35:59 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'] 21:35:59 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 21:36:00 INFO - (blobuploader) - INFO - Open directory for files ... 21:36:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:36:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:36:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:01 INFO - (blobuploader) - INFO - Done attempting. 21:36:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:36:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:36:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:02 INFO - (blobuploader) - INFO - Done attempting. 21:36:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:36:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:36:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:11 INFO - (blobuploader) - INFO - Done attempting. 21:36:11 INFO - (blobuploader) - INFO - Iteration through files over. 21:36:11 INFO - Return code: 0 21:36:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:36:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:36:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/98e76c6fe7cba337e1185e00dd440dd15b9ea57d6f2711c220bf22f4f3c154f76d22d52d0a06cfac9325993121a6b25739f0a1a869365799f23daff8e455b61e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0bbe42cae21a6f348fd7acc749a98a880e7eed52d8d89bd369faf8bc891449ba0c569d831b83bee1537c0e1ea60d961bdabee3aa5c3667139d4d3544caa92f38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f457a607842c1d7e8f0ee930cbbbdca5b338e4833da0919ca68e071955109156c07ca4ee3e26b088c82628448784f2114e5e8dafaa7b8ae6479451cdb43712eb"} 21:36:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:36:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:36:11 INFO - Contents: 21:36:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/98e76c6fe7cba337e1185e00dd440dd15b9ea57d6f2711c220bf22f4f3c154f76d22d52d0a06cfac9325993121a6b25739f0a1a869365799f23daff8e455b61e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0bbe42cae21a6f348fd7acc749a98a880e7eed52d8d89bd369faf8bc891449ba0c569d831b83bee1537c0e1ea60d961bdabee3aa5c3667139d4d3544caa92f38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f457a607842c1d7e8f0ee930cbbbdca5b338e4833da0919ca68e071955109156c07ca4ee3e26b088c82628448784f2114e5e8dafaa7b8ae6479451cdb43712eb"} 21:36:11 INFO - Running post-run listener: copy_logs_to_upload_dir 21:36:11 INFO - Copying logs to upload dir... 21:36:11 INFO - mkdir: /builds/slave/test/build/upload/logs 21:36:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.010852 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-08-07 21:36:11.815995) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 21:36:11.820015) ========= 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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/98e76c6fe7cba337e1185e00dd440dd15b9ea57d6f2711c220bf22f4f3c154f76d22d52d0a06cfac9325993121a6b25739f0a1a869365799f23daff8e455b61e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0bbe42cae21a6f348fd7acc749a98a880e7eed52d8d89bd369faf8bc891449ba0c569d831b83bee1537c0e1ea60d961bdabee3aa5c3667139d4d3544caa92f38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f457a607842c1d7e8f0ee930cbbbdca5b338e4833da0919ca68e071955109156c07ca4ee3e26b088c82628448784f2114e5e8dafaa7b8ae6479451cdb43712eb"} build_url:https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011342 build_url: 'https://queue.taskcluster.net/v1/task/cfvVRaDyTyWI9uEX0hV1jg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/98e76c6fe7cba337e1185e00dd440dd15b9ea57d6f2711c220bf22f4f3c154f76d22d52d0a06cfac9325993121a6b25739f0a1a869365799f23daff8e455b61e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0bbe42cae21a6f348fd7acc749a98a880e7eed52d8d89bd369faf8bc891449ba0c569d831b83bee1537c0e1ea60d961bdabee3aa5c3667139d4d3544caa92f38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f457a607842c1d7e8f0ee930cbbbdca5b338e4833da0919ca68e071955109156c07ca4ee3e26b088c82628448784f2114e5e8dafaa7b8ae6479451cdb43712eb"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 21:36:11.906611) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 21:36:11.906934) ========= 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.5EFcxbS4x3/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.Va9ZpPB7KQ/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.005459 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 21:36:11.932315) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 21:36:11.932600) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 21:36:11.932867) ========= ========= Total master_lag: 0.27 =========