builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0277 starttime: 1470849093.89 results: success (0) buildid: 20160810075615 builduid: 52dc0dcd0e8e43c89f8e40accef64bd1 revision: f9f5b652c83728814cb5134294d351133b53e938 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 10:11:33.892881) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 10:11:33.893346) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 10:11:33.893643) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 10:11:34.061054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 10:11:34.061377) ========= 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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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-10 10:11:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.00M=0.001s 2016-08-10 10:11:34 (8.00 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121202 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 10:11:34.219762) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 10:11:34.220044) ========= 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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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.020354 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 10:11:34.279885) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 10:11:34.280196) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev f9f5b652c83728814cb5134294d351133b53e938 --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 f9f5b652c83728814cb5134294d351133b53e938 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IfoYh8pKkW/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.NdBNBhVbz2/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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-10 10:11:34,365 truncating revision to first 12 chars 2016-08-10 10:11:34,365 Setting DEBUG logging. 2016-08-10 10:11:34,365 attempt 1/10 2016-08-10 10:11:34,365 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/f9f5b652c837?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-10 10:11:35,523 unpacking tar archive at: autoland-f9f5b652c837/testing/mozharness/ program finished with exit code 0 elapsedTime=1.430222 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 10:11:35.737775) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 10:11:35.738053) ========= script_repo_revision: f9f5b652c83728814cb5134294d351133b53e938 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 10:11:35.738429) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 10:11:35.738715) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 10:11:35.756386) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-08-10 10:11:35.756754) ========= /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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 10:11:35 INFO - MultiFileLogger online at 20160810 10:11:35 in /builds/slave/test 10:11:35 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 10:11:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:11:35 INFO - {'append_to_log': False, 10:11:35 INFO - 'base_work_dir': '/builds/slave/test', 10:11:35 INFO - 'blob_upload_branch': 'Autoland', 10:11:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:11:35 INFO - 'branch': 'Autoland', 10:11:35 INFO - 'buildbot_json_path': 'buildprops.json', 10:11:35 INFO - 'config_files': ('talos/mac_config.py',), 10:11:35 INFO - 'default_actions': ('clobber', 10:11:35 INFO - 'read-buildbot-config', 10:11:35 INFO - 'download-and-extract', 10:11:35 INFO - 'populate-webroot', 10:11:35 INFO - 'create-virtualenv', 10:11:35 INFO - 'install', 10:11:35 INFO - 'run-tests'), 10:11:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:11:35 INFO - 'download_minidump_stackwalk': True, 10:11:35 INFO - 'download_symbols': 'ondemand', 10:11:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:11:35 INFO - 'tooltool.py': '/tools/tooltool.py', 10:11:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:11:35 INFO - '/tools/misc-python/virtualenv.py')}, 10:11:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:11:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:11:35 INFO - 'installer_path': 'installer.exe', 10:11:35 INFO - 'log_level': 'info', 10:11:35 INFO - 'log_name': 'talos', 10:11:35 INFO - 'log_to_console': True, 10:11:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:11:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:11:35 INFO - 'opt_config_files': (), 10:11:35 INFO - 'pip_index': False, 10:11:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:11:35 INFO - 'cmd': ('bash', 10:11:35 INFO - '-c', 10:11:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:11:35 INFO - 'enabled': True, 10:11:35 INFO - 'halt_on_failure': False, 10:11:35 INFO - 'name': 'check_screen_resolution'},), 10:11:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:11:35 INFO - 'cmd': ('bash', 10:11:35 INFO - '-c', 10:11:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:11:35 INFO - 'enabled': True, 10:11:35 INFO - 'halt_on_failure': False, 10:11:35 INFO - 'name': 'check_screen_resolution'},), 10:11:35 INFO - 'run_cmd_checks_enabled': True, 10:11:35 INFO - 'sps_profile': False, 10:11:35 INFO - 'sps_profile_interval': 0, 10:11:35 INFO - 'suite': 'dromaeojs', 10:11:35 INFO - 'system_bits': '32', 10:11:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:11:35 INFO - 'title': 't-yosemite-r7-0277', 10:11:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:11:35 INFO - 'use_talos_json': True, 10:11:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:11:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:11:35 INFO - 'work_dir': 'build'} 10:11:35 INFO - [mozharness: 2016-08-10 17:11:35.945926Z] Running clobber step. 10:11:35 INFO - Running pre-action listener: _resource_record_pre_action 10:11:35 INFO - Running main action method: clobber 10:11:35 INFO - rmtree: /builds/slave/test/build 10:11:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:11:37 INFO - Running post-action listener: _resource_record_post_action 10:11:37 INFO - [mozharness: 2016-08-10 17:11:37.371873Z] Finished clobber step (success) 10:11:37 INFO - [mozharness: 2016-08-10 17:11:37.372033Z] Running read-buildbot-config step. 10:11:37 INFO - Running pre-action listener: _resource_record_pre_action 10:11:37 INFO - Running main action method: read_buildbot_config 10:11:37 INFO - Using buildbot properties: 10:11:37 INFO - { 10:11:37 INFO - "project": "", 10:11:37 INFO - "product": "firefox", 10:11:37 INFO - "script_repo_revision": "production", 10:11:37 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 10:11:37 INFO - "repository": "", 10:11:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 10:11:37 INFO - "buildid": "20160810075615", 10:11:37 INFO - "pgo_build": "False", 10:11:37 INFO - "basedir": "/builds/slave/test", 10:11:37 INFO - "buildnumber": 61, 10:11:37 INFO - "slavename": "t-yosemite-r7-0277", 10:11:37 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 10:11:37 INFO - "platform": "yosemite_r7", 10:11:37 INFO - "scheduler": "tests-autoland-macosx64-talos", 10:11:37 INFO - "branch": "autoland", 10:11:37 INFO - "repo_path": "integration/autoland", 10:11:37 INFO - "revision": "f9f5b652c83728814cb5134294d351133b53e938", 10:11:37 INFO - "stage_platform": "macosx64", 10:11:37 INFO - "builduid": "52dc0dcd0e8e43c89f8e40accef64bd1", 10:11:37 INFO - "slavebuilddir": "test" 10:11:37 INFO - } 10:11:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:11:37 INFO - Running post-action listener: _resource_record_post_action 10:11:37 INFO - [mozharness: 2016-08-10 17:11:37.377023Z] Finished read-buildbot-config step (success) 10:11:37 INFO - [mozharness: 2016-08-10 17:11:37.377176Z] Running download-and-extract step. 10:11:37 INFO - Running pre-action listener: _resource_record_pre_action 10:11:37 INFO - Running main action method: download_and_extract 10:11:37 INFO - mkdir: /builds/slave/test/build/tests 10:11:37 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')]} 10:11:37 INFO - trying https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:11:37 INFO - Downloading https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/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 10:11:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/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 10:11:37 INFO - Downloaded 1199 bytes. 10:11:37 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:11:37 INFO - Using the following test package requirements: 10:11:37 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:11:37 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:11:37 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:11:37 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:11:37 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:11:37 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:11:37 INFO - u'jsshell-mac.zip'], 10:11:37 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:11:37 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:11:37 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:11:37 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:11:37 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:11:37 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:11:37 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:11:37 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:11:37 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:11:37 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:11:37 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:11:37 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:11:37 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')]} 10:11:37 INFO - trying https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:11:37 INFO - Downloading https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/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 10:11:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/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 10:11:38 INFO - Downloaded 16325966 bytes. 10:11:38 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'] 10:11:38 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 10:11:41 INFO - Return code: 0 10:11:41 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 10:11:41 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')]} 10:11:41 INFO - trying https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:11:41 INFO - Downloading https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/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 10:11:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/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 10:11:42 INFO - Downloaded 16325966 bytes. 10:11:42 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'] 10:11:42 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 10:11:46 INFO - Return code: 0 10:11:46 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')]} 10:11:46 INFO - trying https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:11:46 INFO - Downloading https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/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 10:11:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/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 10:11:47 INFO - Downloaded 14413719 bytes. 10:11:47 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'] 10:11:47 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 10:11:48 INFO - Return code: 0 10:11: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')]} 10:11:48 INFO - trying https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:11:48 INFO - Downloading https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:11:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/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 10:11:54 INFO - Downloaded 110940053 bytes. 10:11:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:11:54 INFO - mkdir: /builds/slave/test/properties 10:11:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:11:54 INFO - Writing to file /builds/slave/test/properties/build_url 10:11:54 INFO - Contents: 10:11:54 INFO - build_url:https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:11:54 INFO - Running post-action listener: _resource_record_post_action 10:11:54 INFO - Running post-action listener: set_extra_try_arguments 10:11:54 INFO - [mozharness: 2016-08-10 17:11:54.982582Z] Finished download-and-extract step (success) 10:11:54 INFO - [mozharness: 2016-08-10 17:11:54.982732Z] Running populate-webroot step. 10:11:54 INFO - Running pre-action listener: _resource_record_pre_action 10:11:54 INFO - Running main action method: populate_webroot 10:11:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:11:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:11:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:11:54 INFO - '--tppagecycles', 10:11:54 INFO - '7'], 10:11:54 INFO - 'tests': ['tsvgm']}}, 10:11:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:11:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:11:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:11:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:11:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:11:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:11:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:54 INFO - 'talos_options': ['--disable-e10s'], 10:11:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:11:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:11:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:54 INFO - 'talos_options': ['--disable-e10s'], 10:11:54 INFO - 'tests': ['damp', 'tps']}, 10:11:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:54 INFO - 'tests': ['damp', 'tps']}, 10:11:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:11:54 INFO - 'tests': ['dromaeo_dom']}, 10:11:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:11:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:11:54 INFO - 'tests': ['basic_compositor_video']}, 10:11:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:11:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:11:54 INFO - 'tests': ['a11yr', 10:11:54 INFO - 'ts_paint', 10:11:54 INFO - 'tpaint', 10:11:54 INFO - 'sessionrestore', 10:11:54 INFO - 'sessionrestore_no_auto_restore', 10:11:54 INFO - 'tabpaint']}, 10:11:54 INFO - 'other-e10s': {'tests': ['a11yr', 10:11:54 INFO - 'ts_paint', 10:11:54 INFO - 'tpaint', 10:11:54 INFO - 'sessionrestore', 10:11:54 INFO - 'sessionrestore_no_auto_restore', 10:11:54 INFO - 'tabpaint']}, 10:11:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:11:54 INFO - 'ts_paint', 10:11:54 INFO - 'tpaint', 10:11:54 INFO - 'sessionrestore', 10:11:54 INFO - 'sessionrestore_no_auto_restore', 10:11:54 INFO - 'tabpaint']}, 10:11:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:11:54 INFO - 'ts_paint', 10:11:54 INFO - 'tpaint', 10:11:54 INFO - 'sessionrestore', 10:11:54 INFO - 'sessionrestore_no_auto_restore', 10:11:54 INFO - 'tabpaint']}, 10:11:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:11:54 INFO - 'tests': ['a11yr', 10:11:54 INFO - 'ts_paint', 10:11:54 INFO - 'tpaint', 10:11:54 INFO - 'sessionrestore', 10:11:54 INFO - 'sessionrestore_no_auto_restore', 10:11:54 INFO - 'tabpaint']}, 10:11:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:11:54 INFO - 'tests': ['a11yr', 10:11:54 INFO - 'ts_paint', 10:11:54 INFO - 'tpaint', 10:11:54 INFO - 'sessionrestore', 10:11:54 INFO - 'sessionrestore_no_auto_restore', 10:11:54 INFO - 'tabpaint']}, 10:11:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:11:54 INFO - 'tests': ['tsvgx', 10:11:54 INFO - 'tsvgr_opacity', 10:11:54 INFO - 'tart', 10:11:54 INFO - 'tscrollx', 10:11:54 INFO - 'cart']}, 10:11:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:11:54 INFO - 'tsvgr_opacity', 10:11:54 INFO - 'tart', 10:11:54 INFO - 'tscrollx', 10:11:54 INFO - 'cart']}, 10:11:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:54 INFO - 'talos_options': ['--disable-e10s'], 10:11:54 INFO - 'tests': ['tp5o']}, 10:11:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:55 INFO - 'tests': ['tp5o']}, 10:11:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:55 INFO - 'talos_options': ['--disable-e10s', 10:11:55 INFO - '--xperf_path', 10:11:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:11:55 INFO - 'tests': ['tp5n']}, 10:11:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:55 INFO - 'talos_options': ['--xperf_path', 10:11:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:11:55 INFO - 'tests': ['tp5n']}}, 10:11:55 INFO - 'talos.zip': {'path': '', 10:11:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:11:55 INFO - Running post-action listener: _resource_record_post_action 10:11:55 INFO - [mozharness: 2016-08-10 17:11:55.001604Z] Finished populate-webroot step (success) 10:11:55 INFO - [mozharness: 2016-08-10 17:11:55.001740Z] Running create-virtualenv step. 10:11:55 INFO - Running pre-action listener: _resource_record_pre_action 10:11:55 INFO - Running main action method: create_virtualenv 10:11:55 INFO - Creating virtualenv /builds/slave/test/build/venv 10:11:55 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 10:11:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:11:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:11:55 INFO - Using real prefix '/tools/python27' 10:11:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:11:55 INFO - Installing distribute.............................................................................................................................................................................................done. 10:11:58 INFO - Installing pip.................done. 10:11:58 INFO - Return code: 0 10:11:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:11:58 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')]} 10:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:58 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')]} 10:11:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:58 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 0x103250a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032e5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98b0567970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031b4d30>, '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 0x102f19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031acb20>, '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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 10:11:58 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 10:11:58 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 10:11:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfoYh8pKkW/Render', 10:11:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:58 INFO - 'HOME': '/Users/cltbld', 10:11:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:58 INFO - 'LOGNAME': 'cltbld', 10:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:58 INFO - 'MOZ_NO_REMOTE': '1', 10:11:58 INFO - 'NO_EM_RESTART': '1', 10:11:58 INFO - 'PAGER': '/bin/cat', 10:11:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:58 INFO - 'PWD': '/builds/slave/test', 10:11:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:58 INFO - 'SHELL': '/bin/bash', 10:11:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NdBNBhVbz2/Listeners', 10:11:58 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 10:11:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:58 INFO - 'USER': 'cltbld', 10:11:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:58 INFO - 'XPC_FLAGS': '0x0', 10:11:58 INFO - 'XPC_SERVICE_NAME': '0', 10:11:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:58 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 10:11:58 INFO - Cleaning up... 10:11:58 INFO - Return code: 0 10:11:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:11:58 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')]} 10:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:58 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')]} 10:11:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:58 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 0x103250a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032e5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98b0567970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031b4d30>, '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 0x102f19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031acb20>, '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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 10:11:58 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 10:11:58 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 10:11:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfoYh8pKkW/Render', 10:11:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:58 INFO - 'HOME': '/Users/cltbld', 10:11:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:58 INFO - 'LOGNAME': 'cltbld', 10:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:58 INFO - 'MOZ_NO_REMOTE': '1', 10:11:58 INFO - 'NO_EM_RESTART': '1', 10:11:58 INFO - 'PAGER': '/bin/cat', 10:11:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:58 INFO - 'PWD': '/builds/slave/test', 10:11:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:58 INFO - 'SHELL': '/bin/bash', 10:11:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NdBNBhVbz2/Listeners', 10:11:58 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 10:11:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:58 INFO - 'USER': 'cltbld', 10:11:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:58 INFO - 'XPC_FLAGS': '0x0', 10:11:58 INFO - 'XPC_SERVICE_NAME': '0', 10:11:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:59 INFO - Downloading/unpacking psutil>=3.1.1 10:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:12:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:12:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:12:02 INFO - Installing collected packages: psutil 10:12:02 INFO - Running setup.py install for psutil 10:12:02 INFO - building 'psutil._psutil_osx' extension 10:12:02 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 10:12:03 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 10:12:03 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 10:12:03 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 10:12:03 INFO - building 'psutil._psutil_posix' extension 10:12:03 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 10:12:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:12:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:12:03 INFO - ^ 10:12:03 INFO - 1 warning generated. 10:12:03 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 10:12:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:12:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:12:03 INFO - Successfully installed psutil 10:12:03 INFO - Cleaning up... 10:12:03 INFO - Return code: 0 10:12:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:12:03 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')]} 10:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:03 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')]} 10:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:03 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 0x103250a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032e5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98b0567970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031b4d30>, '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 0x102f19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031acb20>, '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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 10:12:03 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 10:12:03 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 10:12:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfoYh8pKkW/Render', 10:12:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:03 INFO - 'HOME': '/Users/cltbld', 10:12:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:03 INFO - 'LOGNAME': 'cltbld', 10:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:03 INFO - 'MOZ_NO_REMOTE': '1', 10:12:03 INFO - 'NO_EM_RESTART': '1', 10:12:03 INFO - 'PAGER': '/bin/cat', 10:12:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:03 INFO - 'PWD': '/builds/slave/test', 10:12:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:03 INFO - 'SHELL': '/bin/bash', 10:12:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NdBNBhVbz2/Listeners', 10:12:03 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 10:12:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:03 INFO - 'USER': 'cltbld', 10:12:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:03 INFO - 'XPC_FLAGS': '0x0', 10:12:03 INFO - 'XPC_SERVICE_NAME': '0', 10:12:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:12:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:12:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:12:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:12:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:12:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:12:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:12:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:12:06 INFO - Installing collected packages: mozsystemmonitor 10:12:06 INFO - Running setup.py install for mozsystemmonitor 10:12:06 INFO - Successfully installed mozsystemmonitor 10:12:06 INFO - Cleaning up... 10:12:06 INFO - Return code: 0 10:12:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103250a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032e5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98b0567970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031b4d30>, '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 0x102f19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031acb20>, '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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 10:12:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:12:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:12:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfoYh8pKkW/Render', 10:12:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:06 INFO - 'HOME': '/Users/cltbld', 10:12:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:06 INFO - 'LOGNAME': 'cltbld', 10:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:06 INFO - 'MOZ_NO_REMOTE': '1', 10:12:06 INFO - 'NO_EM_RESTART': '1', 10:12:06 INFO - 'PAGER': '/bin/cat', 10:12:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:06 INFO - 'PWD': '/builds/slave/test', 10:12:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:06 INFO - 'SHELL': '/bin/bash', 10:12:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NdBNBhVbz2/Listeners', 10:12:06 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 10:12:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:06 INFO - 'USER': 'cltbld', 10:12:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:06 INFO - 'XPC_FLAGS': '0x0', 10:12:06 INFO - 'XPC_SERVICE_NAME': '0', 10:12:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:06 INFO - Downloading/unpacking jsonschema==2.5.1 10:12:06 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 10:12:06 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 10:12:06 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 10:12:06 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 10:12:06 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 10:12:06 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 10:12:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:12:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:12:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:12:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:12:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:12:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:12:09 INFO - Downloading functools32-3.2.3-2.tar.gz 10:12:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:12:09 INFO - warning: no files found matching '*.txt' 10:12:09 INFO - no previously-included directories found matching 'build' 10:12:09 INFO - no previously-included directories found matching 'dist' 10:12:09 INFO - no previously-included directories found matching '.git*' 10:12:09 INFO - Installing collected packages: jsonschema, functools32 10:12:09 INFO - Running setup.py install for functools32 10:12:09 INFO - warning: no files found matching '*.txt' 10:12:09 INFO - no previously-included directories found matching 'build' 10:12:09 INFO - no previously-included directories found matching 'dist' 10:12:09 INFO - no previously-included directories found matching '.git*' 10:12:09 INFO - Successfully installed jsonschema functools32 10:12:09 INFO - Cleaning up... 10:12:10 INFO - Return code: 0 10:12:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:12:10 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')]} 10:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:10 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')]} 10:12:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:10 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 0x103250a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032e5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98b0567970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031b4d30>, '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 0x102f19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031acb20>, '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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 10:12:10 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 10:12:10 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 10:12:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfoYh8pKkW/Render', 10:12:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:10 INFO - 'HOME': '/Users/cltbld', 10:12:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:10 INFO - 'LOGNAME': 'cltbld', 10:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:10 INFO - 'MOZ_NO_REMOTE': '1', 10:12:10 INFO - 'NO_EM_RESTART': '1', 10:12:10 INFO - 'PAGER': '/bin/cat', 10:12:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:10 INFO - 'PWD': '/builds/slave/test', 10:12:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:10 INFO - 'SHELL': '/bin/bash', 10:12:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NdBNBhVbz2/Listeners', 10:12:10 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 10:12:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:10 INFO - 'USER': 'cltbld', 10:12:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:10 INFO - 'XPC_FLAGS': '0x0', 10:12:10 INFO - 'XPC_SERVICE_NAME': '0', 10:12:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:12:10 INFO - Cleaning up... 10:12:10 INFO - Return code: 0 10:12:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:12:10 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')]} 10:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:10 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')]} 10:12:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:10 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 0x103250a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032e5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98b0567970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031b4d30>, '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 0x102f19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031acb20>, '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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 10:12:10 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 10:12:10 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 10:12:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfoYh8pKkW/Render', 10:12:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:10 INFO - 'HOME': '/Users/cltbld', 10:12:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:10 INFO - 'LOGNAME': 'cltbld', 10:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:10 INFO - 'MOZ_NO_REMOTE': '1', 10:12:10 INFO - 'NO_EM_RESTART': '1', 10:12:10 INFO - 'PAGER': '/bin/cat', 10:12:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:10 INFO - 'PWD': '/builds/slave/test', 10:12:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:10 INFO - 'SHELL': '/bin/bash', 10:12:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NdBNBhVbz2/Listeners', 10:12:10 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 10:12:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:10 INFO - 'USER': 'cltbld', 10:12:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:10 INFO - 'XPC_FLAGS': '0x0', 10:12:10 INFO - 'XPC_SERVICE_NAME': '0', 10:12:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:10 INFO - Downloading/unpacking blobuploader==1.2.4 10:12:10 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 10:12:10 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 10:12:10 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 10:12:10 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 10:12:10 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 10:12:10 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 10:12:12 INFO - Downloading blobuploader-1.2.4.tar.gz 10:12:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:12:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:12:13 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 10:12:13 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 10:12:13 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 10:12:13 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 10:12:13 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 10:12:13 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 10:12:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:12:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:12:13 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 10:12:13 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 10:12:13 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 10:12:13 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 10:12:13 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 10:12:13 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 10:12:14 INFO - Downloading docopt-0.6.1.tar.gz 10:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:12:14 INFO - Installing collected packages: blobuploader, requests, docopt 10:12:14 INFO - Running setup.py install for blobuploader 10:12:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:12:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:12:14 INFO - Running setup.py install for requests 10:12:14 INFO - Running setup.py install for docopt 10:12:15 INFO - Successfully installed blobuploader requests docopt 10:12:15 INFO - Cleaning up... 10:12:15 INFO - Return code: 0 10:12:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:15 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 0x103250a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032e5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98b0567970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031b4d30>, '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 0x102f19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031acb20>, '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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 10:12:15 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 10:12:15 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 10:12:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfoYh8pKkW/Render', 10:12:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:15 INFO - 'HOME': '/Users/cltbld', 10:12:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:15 INFO - 'LOGNAME': 'cltbld', 10:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:15 INFO - 'MOZ_NO_REMOTE': '1', 10:12:15 INFO - 'NO_EM_RESTART': '1', 10:12:15 INFO - 'PAGER': '/bin/cat', 10:12:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:15 INFO - 'PWD': '/builds/slave/test', 10:12:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:15 INFO - 'SHELL': '/bin/bash', 10:12:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NdBNBhVbz2/Listeners', 10:12:15 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 10:12:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:15 INFO - 'USER': 'cltbld', 10:12:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:15 INFO - 'XPC_FLAGS': '0x0', 10:12:15 INFO - 'XPC_SERVICE_NAME': '0', 10:12:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:12:15 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-QpNcKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:12:15 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-hjXrsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:12:15 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-mgpMRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:12:15 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-JQceom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:12:15 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-NVMwwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:12:15 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-MRAAS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:12:16 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-s4ewDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:12:16 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-3yYBFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:12:16 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-xBV0QD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:12:16 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-ja4geE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:12:16 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-Wk9eMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:12:16 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-7FE0DT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:12:16 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-gQpkUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:12:16 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-ogKHnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:12:16 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-HKCQM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:12:16 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-jvj0eb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:12:17 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-rrRkwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:12:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:12:17 INFO - Running setup.py install for manifestparser 10:12:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:12:17 INFO - Running setup.py install for mozcrash 10:12:17 INFO - Running setup.py install for mozdebug 10:12:17 INFO - Running setup.py install for mozdevice 10:12:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:12:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:12:17 INFO - Running setup.py install for mozfile 10:12:17 INFO - Running setup.py install for mozhttpd 10:12:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:12:18 INFO - Running setup.py install for mozinfo 10:12:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:12:18 INFO - Running setup.py install for mozInstall 10:12:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:12:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:12:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:12:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:12:18 INFO - Running setup.py install for mozleak 10:12:18 INFO - Running setup.py install for mozlog 10:12:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:12:18 INFO - Running setup.py install for moznetwork 10:12:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:12:18 INFO - Running setup.py install for mozprocess 10:12:18 INFO - Running setup.py install for mozprofile 10:12:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:12:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:12:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:12:19 INFO - Running setup.py install for mozrunner 10:12:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:12:19 INFO - Running setup.py install for mozscreenshot 10:12:19 INFO - Running setup.py install for moztest 10:12:19 INFO - Running setup.py install for mozversion 10:12:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:12:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:12:19 INFO - Cleaning up... 10:12:19 INFO - Return code: 0 10:12:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:12:19 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')]} 10:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:19 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')]} 10:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:19 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 0x103250a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032e5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98b0567970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031b4d30>, '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 0x102f19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031acb20>, '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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 10:12:19 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 10:12:19 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 10:12:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfoYh8pKkW/Render', 10:12:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:19 INFO - 'HOME': '/Users/cltbld', 10:12:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:19 INFO - 'LOGNAME': 'cltbld', 10:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:19 INFO - 'MOZ_NO_REMOTE': '1', 10:12:19 INFO - 'NO_EM_RESTART': '1', 10:12:19 INFO - 'PAGER': '/bin/cat', 10:12:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:19 INFO - 'PWD': '/builds/slave/test', 10:12:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:19 INFO - 'SHELL': '/bin/bash', 10:12:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NdBNBhVbz2/Listeners', 10:12:19 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 10:12:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:19 INFO - 'USER': 'cltbld', 10:12:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:19 INFO - 'XPC_FLAGS': '0x0', 10:12:19 INFO - 'XPC_SERVICE_NAME': '0', 10:12:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:12:20 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-xWdYnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:12:20 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)) 10:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:12:20 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-oWwuj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:12:20 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)) 10:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:12:20 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-Dqhn40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:12:20 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)) 10:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:12:20 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-Znc8N4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:12:20 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)) 10:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:12:20 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-vdG1EQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:12:20 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)) 10:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:12:20 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-ScHg0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:12:20 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)) 10:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:12:20 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-HbBVXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:12:20 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)) 10:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:12:20 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-oFTHyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:12:20 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)) 10:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:12:20 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-Vtw_ho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:12:21 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)) 10:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:12:21 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-zgG8FI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:12:21 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)) 10:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:12:21 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-mkrDxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:12:21 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)) 10:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:12:21 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-zBpXX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:12:21 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)) 10:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:12:21 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-6ZXKTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:12:21 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)) 10:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:12:21 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-gngs4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:12:21 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)) 10:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:12:21 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-8GWE_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:12:21 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)) 10:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:12:21 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-EfXlSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:12:21 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)) 10:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:12:21 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-8NUJIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:12:21 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)) 10:12:21 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)) 10:12:21 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)) 10:12:21 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)) 10:12:21 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)) 10:12:21 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)) 10:12:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:12:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:12:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:12:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:12:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:12:24 INFO - Downloading blessings-1.6.tar.gz 10:12:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:12:24 INFO - Installing collected packages: blessings 10:12:24 INFO - Running setup.py install for blessings 10:12:24 INFO - Successfully installed blessings 10:12:24 INFO - Cleaning up... 10:12:24 INFO - Return code: 0 10:12:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:12:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:12:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:12:24 INFO - Reading from file tmpfile_stdout 10:12:24 INFO - Current package versions: 10:12:24 INFO - blessings == 1.6 10:12:24 INFO - blobuploader == 1.2.4 10:12:24 INFO - docopt == 0.6.1 10:12:24 INFO - functools32 == 3.2.3-2 10:12:24 INFO - jsonschema == 2.5.1 10:12:24 INFO - manifestparser == 1.1 10:12:24 INFO - mozInstall == 1.12 10:12:24 INFO - mozcrash == 0.17 10:12:24 INFO - mozdebug == 0.1 10:12:24 INFO - mozdevice == 0.48 10:12:24 INFO - mozfile == 1.2 10:12:24 INFO - mozhttpd == 0.7 10:12:24 INFO - mozinfo == 0.9 10:12:24 INFO - mozleak == 0.1 10:12:24 INFO - mozlog == 3.3 10:12:24 INFO - moznetwork == 0.27 10:12:24 INFO - mozprocess == 0.23 10:12:24 INFO - mozprofile == 0.28 10:12:24 INFO - mozrunner == 6.12 10:12:24 INFO - mozscreenshot == 0.1 10:12:24 INFO - mozsystemmonitor == 0.3 10:12:24 INFO - moztest == 0.7 10:12:24 INFO - mozversion == 1.4 10:12:24 INFO - psutil == 3.1.1 10:12:24 INFO - requests == 1.2.3 10:12:24 INFO - wsgiref == 0.1.2 10:12:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:12:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:24 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 0x103250a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032e5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98b0567970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031b4d30>, '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 0x102f19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031acb20>, '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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 10:12:24 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 10:12:24 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 10:12:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfoYh8pKkW/Render', 10:12:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:25 INFO - 'HOME': '/Users/cltbld', 10:12:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:25 INFO - 'LOGNAME': 'cltbld', 10:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:25 INFO - 'MOZ_NO_REMOTE': '1', 10:12:25 INFO - 'NO_EM_RESTART': '1', 10:12:25 INFO - 'PAGER': '/bin/cat', 10:12:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:25 INFO - 'PWD': '/builds/slave/test', 10:12:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:25 INFO - 'SHELL': '/bin/bash', 10:12:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NdBNBhVbz2/Listeners', 10:12:25 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 10:12:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:25 INFO - 'USER': 'cltbld', 10:12:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:25 INFO - 'XPC_FLAGS': '0x0', 10:12:25 INFO - 'XPC_SERVICE_NAME': '0', 10:12:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:25 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)) 10:12:25 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)) 10:12:25 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)) 10:12:25 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)) 10:12:25 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)) 10:12:25 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)) 10:12:25 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)) 10:12:25 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)) 10:12:25 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)) 10:12:25 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)) 10:12:25 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)) 10:12:25 INFO - Cleaning up... 10:12:25 INFO - Return code: 0 10:12:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:12:25 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')]} 10:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:25 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')]} 10:12:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:25 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 0x103250a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032e5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98b0567970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031b4d30>, '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 0x102f19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031acb20>, '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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 10:12:25 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 10:12:25 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 10:12:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfoYh8pKkW/Render', 10:12:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:25 INFO - 'HOME': '/Users/cltbld', 10:12:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:25 INFO - 'LOGNAME': 'cltbld', 10:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:25 INFO - 'MOZ_NO_REMOTE': '1', 10:12:25 INFO - 'NO_EM_RESTART': '1', 10:12:25 INFO - 'PAGER': '/bin/cat', 10:12:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:25 INFO - 'PWD': '/builds/slave/test', 10:12:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:25 INFO - 'SHELL': '/bin/bash', 10:12:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NdBNBhVbz2/Listeners', 10:12:25 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 10:12:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:25 INFO - 'USER': 'cltbld', 10:12:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:25 INFO - 'XPC_FLAGS': '0x0', 10:12:25 INFO - 'XPC_SERVICE_NAME': '0', 10:12:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:12:25 INFO - Cleaning up... 10:12:25 INFO - Return code: 0 10:12:25 INFO - Running post-action listener: _resource_record_post_action 10:12:25 INFO - Running post-action listener: _start_resource_monitoring 10:12:25 INFO - Starting resource monitoring. 10:12:25 INFO - [mozharness: 2016-08-10 17:12:25.560742Z] Finished create-virtualenv step (success) 10:12:25 INFO - [mozharness: 2016-08-10 17:12:25.561736Z] Running install step. 10:12:25 INFO - Running pre-action listener: _resource_record_pre_action 10:12:25 INFO - Running main action method: install 10:12:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:12:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:12:25 INFO - Reading from file tmpfile_stdout 10:12:25 INFO - Detecting whether we're running mozinstall >=1.0... 10:12:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:12:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:12:25 INFO - Reading from file tmpfile_stdout 10:12:25 INFO - Output received: 10:12:25 INFO - Usage: mozinstall [options] installer 10:12:25 INFO - Options: 10:12:25 INFO - -h, --help show this help message and exit 10:12:25 INFO - -d DEST, --destination=DEST 10:12:25 INFO - Directory to install application into. [default: 10:12:25 INFO - "/builds/slave/test"] 10:12:25 INFO - --app=APP Application being installed. [default: firefox] 10:12:25 INFO - mkdir: /builds/slave/test/build/application 10:12:25 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'] 10:12:25 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 10:12:59 INFO - Reading from file tmpfile_stdout 10:12:59 INFO - Output received: 10:12:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:12:59 INFO - Running post-action listener: _resource_record_post_action 10:12:59 INFO - [mozharness: 2016-08-10 17:12:59.873287Z] Finished install step (success) 10:12:59 INFO - [mozharness: 2016-08-10 17:12:59.873460Z] Running run-tests step. 10:12:59 INFO - Running pre-action listener: _resource_record_pre_action 10:12:59 INFO - Running main action method: run_tests 10:12:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:12:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:12:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:12:59 INFO - 2016-08-10 10:12:59.887 screenresolution[3080:53022] starting screenresolution argv=screenresolution get 10:12:59 INFO - 2016-08-10 10:12:59.890 screenresolution[3080:53022] Display 0: 1600x1200x32@60 10:12:59 INFO - 2016-08-10 10:12:59.896 screenresolution[3081:53024] starting screenresolution argv=screenresolution list 10:12:59 INFO - Available Modes on Display 0 10:12:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:12:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:12:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:12:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:13:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:13:00 INFO - Intel Iris: 10:13:00 INFO - Chipset Model: Intel Iris 10:13:00 INFO - Type: GPU 10:13:00 INFO - Bus: Built-In 10:13:00 INFO - VRAM (Dynamic, Max): 1536 MB 10:13:00 INFO - Vendor: Intel (0x8086) 10:13:00 INFO - Device ID: 0x0a2e 10:13:00 INFO - Revision ID: 0x0009 10:13:00 INFO - Displays: 10:13:00 INFO - Display: 10:13:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:13:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:13:00 INFO - Display Serial Number: 1600x1200 60 10:13:00 INFO - Main Display: Yes 10:13:00 INFO - Mirror: Off 10:13:00 INFO - Online: Yes 10:13:00 INFO - Rotation: Supported 10:13:00 INFO - Return code: 0 10:13:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:13:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:13:00 INFO - Python 2.7.3 10:13:00 INFO - Return code: 0 10:13:00 INFO - grabbing minidump binary from tooltool 10:13:00 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')]} 10:13:00 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 0x1031b4d30>, '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 0x102f19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031acb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:13:00 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 10:13:00 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 10:13:00 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 10:13:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:13:00 INFO - Return code: 0 10:13:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:13:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:13:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:13:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:13:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:13:00 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/GaPyK9McR-6Jt_GRuI7vFw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0277', '--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 10:13:00 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/GaPyK9McR-6Jt_GRuI7vFw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0277 --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 10:13:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfoYh8pKkW/Render', 10:13:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:00 INFO - 'HOME': '/Users/cltbld', 10:13:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:00 INFO - 'LOGNAME': 'cltbld', 10:13:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:13:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:00 INFO - 'MOZ_NO_REMOTE': '1', 10:13:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:13:00 INFO - 'NO_EM_RESTART': '1', 10:13:00 INFO - 'PAGER': '/bin/cat', 10:13:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:00 INFO - 'PWD': '/builds/slave/test', 10:13:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:13:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:00 INFO - 'SHELL': '/bin/bash', 10:13:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NdBNBhVbz2/Listeners', 10:13:00 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 10:13:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:00 INFO - 'USER': 'cltbld', 10:13:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:00 INFO - 'XPC_FLAGS': '0x0', 10:13:00 INFO - 'XPC_SERVICE_NAME': '0', 10:13:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:00 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/GaPyK9McR-6Jt_GRuI7vFw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0277', '--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 10:13:00 INFO - mozversion application_buildid: 20160810075615 10:13:00 INFO - mozversion application_changeset: f9f5b652c83728814cb5134294d351133b53e938 10:13:00 INFO - mozversion application_display_name: Nightly 10:13:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:13:00 INFO - mozversion application_name: Firefox 10:13:00 INFO - mozversion application_remotingname: firefox 10:13:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:13:00 INFO - mozversion application_vendor: Mozilla 10:13:00 INFO - mozversion application_version: 51.0a1 10:13:00 INFO - mozversion platform_buildid: 20160810075615 10:13:00 INFO - mozversion platform_changeset: f9f5b652c83728814cb5134294d351133b53e938 10:13:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:13:00 INFO - mozversion platform_version: 51.0a1 10:13:00 INFO - using testdate: 1470849180 10:13:00 INFO - actual date: 1470849180 10:13:00 INFO - starting webserver on 'localhost:51129' 10:13:00 INFO - SUITE-START | Running 2 tests 10:13:00 INFO - TEST-START | dromaeo_css 10:13:00 INFO - operating with platform_type : mac_ 10:13:00 INFO - Initialising browser for dromaeo_css test... 10:13:00 INFO - TEST-INFO | started process 3091 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmp9Q0biu/profile http://localhost:51129/getInfo.html) 10:13:02 INFO - PROCESS | 3091 | __metrics Screen width/height:1600/1200 10:13:02 INFO - PROCESS | 3091 | colorDepth:24 10:13:02 INFO - PROCESS | 3091 | Browser inner width/height: 1024/689 10:13:02 INFO - PROCESS | 3091 | __metrics 10:13:02 INFO - PROCESS | 3091 | 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" 10:13:02 INFO - PROCESS | 3091 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:13:03 INFO - TEST-INFO | 3091: exit 0 10:13:03 INFO - Browser initialized. 10:13:03 INFO - Running cycle 1/1 for dromaeo_css test... 10:13:03 INFO - TEST-INFO | started process 3093 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmp9Q0biu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:13:15 INFO - PROCESS | 3093 | RSS: Main: 243707904 10:13:15 INFO - PROCESS | 3093 | 10:14:07 INFO - PROCESS | 3093 | 1470849247284 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] 10:16:18 INFO - PROCESS | 3093 | Cycle 1(1): loaded http://localhost:51129/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51129/tests/dromaeo/cssquery-ext.html) 10:16:19 INFO - PROCESS | 3093 | RSS: Main: 374374400 10:16:19 INFO - PROCESS | 3093 | 10:19:23 INFO - PROCESS | 3093 | Cycle 1(1): loaded http://localhost:51129/tests/dromaeo/cssquery-ext.html (next: http://localhost:51129/tests/dromaeo/cssquery-jquery.html) 10:19:23 INFO - PROCESS | 3093 | RSS: Main: 381661184 10:19:23 INFO - PROCESS | 3093 | 10:22:26 INFO - PROCESS | 3093 | Cycle 1(1): loaded http://localhost:51129/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51129/tests/dromaeo/cssquery-mootools.html) 10:22:26 INFO - PROCESS | 3093 | RSS: Main: 345202688 10:22:26 INFO - PROCESS | 3093 | 10:25:28 INFO - PROCESS | 3093 | Cycle 1(1): loaded http://localhost:51129/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51129/tests/dromaeo/cssquery-prototype.html) 10:25:29 INFO - PROCESS | 3093 | RSS: Main: 347066368 10:25:29 INFO - PROCESS | 3093 | 10:28:33 INFO - PROCESS | 3093 | Cycle 1(1): loaded http://localhost:51129/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51129/tests/dromaeo/cssquery-yui.html) 10:28:33 INFO - PROCESS | 3093 | RSS: Main: 343191552 10:28:33 INFO - PROCESS | 3093 | 10:31:36 INFO - PROCESS | 3093 | Cycle 1(1): loaded http://localhost:51129/tests/dromaeo/cssquery-yui.html (next: http://localhost:51129/tests/dromaeo/cssquery-dojo.html) 10:31:37 INFO - PROCESS | 3093 | RSS: Main: 506245120 10:31:37 INFO - PROCESS | 3093 | 10:31:37 INFO - PROCESS | 3093 | __start_tp_report 10:31:37 INFO - PROCESS | 3093 | _x_x_mozilla_page_load 10:31:37 INFO - PROCESS | 3093 | _x_x_mozilla_page_load_details 10:31:37 INFO - PROCESS | 3093 | |i|pagename|runs| 10:31:37 INFO - PROCESS | 3093 | |0;dojo.html;2917.1394438640614;3289.67103289671;3308.9669103308975;3327.3678001179787;3375.8818441354556;5517.862053448655;5549.778008879645;5638.661680299175;5645.322561292641;5662.405447428017;4683.695559788612;5581.246531718217;5635.267415236013;5688.516476099526;5739.426057394258;5080.77136528857;5083.694978301295;5150.407703114152;5152.227165925104;5293.073712100387;5210.16637338026;5222.033923724104;5234.92147617786;5259.815906443275;5262.500062494057;5084.008618319429;5136.280920671072;5142.357205349332;5142.56288215504;5174.379074511083;5114.43741188469;5123.2827404163445;5154.969006198775;5191.922121168185;5196.090684130299;5074.416442109168;5083.517065878736;5126.794928202868;5144.382674079097;5197.986392653434;5614.185943038277;5683.602147849652;5698.886022279531;5706.543476521868;5718.0851063830005;6042.546808989343;6138.232720909887;6159.538034647375;6161.44546990773;6163.167972323724;6193.938060619381;6241.65670888101;6256.78101266458;6260.060990851363;6260.5304602154565;5778.508826749731;5782.97108514459;5790.594658373958;5792.160136780143;5792.710364481759;6143.542582966849;6223.471004964629;6229.844253893598;6259.874802504016;6269.62382257066;5962.433568811043;6000.519958403404;6050.57645964787;6083.087536869505;6084.969575152096;6186.0102383618405;6206.317305096436;6207.224096987877;6212.06818977157;6218.844528886814;6076.0885867119405;6096.664683442365;6112.362237678657;6126.153079032661;6166.290876549165;6015.578909476295;6121.4490695837585;6126.08842927457;6167.982282923357;6190.102435146879;4805.183118869801;4875.1712208923855;4875.585575226075;4896.583790377857;4934.358533390637;4317.784110794448;4352.368906508538;4364.476262848479;4374.737515749065;4427.867163985085;4800.687955282895;5478.04134276508;5572.136318870582;5604.97197514018;5629.324481062299;6132.324915438597;6136.509079273647;6172.351903050205;6189.35011823752;6204;5621.269234999506;5627.71861406928;5641.971790141023;5645.2096706461125;5681.630694004876;5362.65142765719;5368.785248590091;5369.731513424314;5376.865578360573;5392.218128371364;6187.3503282154725;6190.288116866528;6228.532860035425;6268.968655156695;6272.247330320209;6212.192414986023;6224;6237.500999919905;6248.937510625017;6269.247690277196;6224.128621993015;6224.657643829632;6257.812265632038;6259.530535209787;6293;5993.280806303272;6061.242344706911;6113.419225173601;6120.8775824484155;6124.724387402489;6225.159603453476;6228.56400051992;6233.096201050732;6237.937620623918;6250.062490626442;6026.457618814327;6033.18551995475;6038.154658347923;6047.606905551125;6109.327973922954;5780.855478613068;5976.9103463448955;6009.06859436788;6023.698815059317;6037.305709843425;5766.740496677576;5883.794067207627;5889.676067816311;5912.994790885646;5924.763009479572;6038.215032045806;6038.396160383926;6045.697715114315;6060.636361818305;6062.393760623902;5691.430856914276;5782.421757824184;5792.623479473989;5845.678487683218;5860.15027820955 10:31:37 INFO - PROCESS | 3093 | |1;ext.html;473.122358025862;485.8469582081644;516.0633450287728;523.9554637855782;535.7803300646735;30695;39877;40440.39339409911;40594;43406.78296608521;42846.928826779345;43346.13307733851;43419.78290108553;43483.56516434834;43483.56516434834;8683.652653893836;9685.95157024214;9866.408015519057;9870.45712485813;9878.950605246964;8832.735017949439;12022.51909923602;12290.631281061582;12293.877061229363;12297.815532767016;12084.879151208463;12155.878441215564;12156.696082597962;12168.965637920788;12174.573889913854;12035.819462708067;12115.39423028845;12119.394030298537;12119.818202726921;12130.332831694253;10063.396196228177;11872.287662740264;11956.222845515014;11963.521459141624;12054.879451205463;40148.799256003535;40459.39310910339;40463.7976810117;40476.59523404758;40653.78038658845;3321.385543674428;3467.098554375855;3529.4000019996406;3595.460680897886;4090.406891000818;30589.08232753021;31549.684503154906;32003.51994720081;32130.357392852013;32167.51748723771;3200.8719651213923;3236.6277878044093;3291.078498020558;3330.3505816365578;3363;517612;518206;519191.84848909185;519705;520023.9999999962;56299.71850140723;56464.870702585715;56581;56614;56676.14985775216;49754;59923.70038149868;60476.697616511636;60608.393916060275;60726.696366517885;16036.278367473491;16160.838391615933;16295.755563666555;16297.511074667778;16321.183940802912;11603;11908.047356211482;12576.308303043237;12704.682382940315;12772.297523636289;14772.187529685756;15002.474913377979;15005.249737513299;15016.47442339513;15236.619084522976;6126.877462450726;6185.319614842364;6276.623402595859;6290.685465726695;6292.276388215322;910.41733290694;930.6324002019164;983.6901376066517;1014.5028935821394;1041.250299784154;30012.54981175284;30105.849470752943;30143.69856301453;30461.543076853864;30532.54201186984;1011.5043628622069;1035.2494441529982;1036.549101141006;1062.3360399750156;1100.9889901101046;15224.238788060553;15547.766783498257;15564.143972081418;15591.610209744847;15952.441664541686;663807;665541;665853;666723;671368;58449;58855.705721471975;58885;59036.40963590395;59059.81880362455;1256.114439320277;1293.198217105382;1293.3985696651102;1390.4507719450962;1406.296851574213;23680.644790328504;27655.86172069207;31241;31845.20386990344;33039.66960330366;53208;55625.44374556364;57024.1446378313;57234.7138264306;60438;613433;618807;618817;619158;620744.8962755158;23357;23907;25836.35409114712;25931.740682592932;26461.809218585597;40855.57005504793;45089.32366014578;46358.768206158755;47349;48358.516414836806;38864.919183485326;39137;39389.16735800991;40355;40502.79748601356;37408.625913740514;39334.40998385084;39375.606243937196;40384.1923161541;40757.1848563033;38003.71977681348;38537;38748.6125138745;38828.41757373585;39028.80485597554;28129.71870281271;29681.25796855096;31016.53475197919;32587.674123258465;34901;21623.675644865656;22444.214452494085;24570.017199311827;25030.37424064413;25830.87084564565 10:31:37 INFO - PROCESS | 3093 | |2;jquery.html;352.75131032622005;368.5724559510967;376.9491118698975;380.70685572109477;402.79256183065723;15043.473478428243;15916.204189790522;20426.795732042676;20431.080601372938;20855.895720521414;18874.905625471853;19839.40481785545;19856.007199639997;20408;20427.693584596243;5265.526102650771;5271.262023316739;5670.971645141769;5886.705664716768;5902.439268269508;5670.6030577859565;5796.44933731297;5801.419858014207;5801.680907550083;5808.622439541437;5516.393196748354;5761.683107429089;5770.2210201622665;5774.133879918004;5824.359320474744;5789.536837053026;5860.355360910296;5878.911816322759;5904.2324497815225;5910.202122713422;5615.466530679579;5824.854378640525;5828.621139625953;5829.621074630136;5840.15317778924;10485.842712359321;13122.934385328108;19532.80467195324;24834.37914052145;25119.24642260735;1620.9189540522927;2070.244360808309;2104.663253879372;2194.7366316042016;2636.2126080598387;9666.275029372826;9787.755306117333;9829.410235385829;9926.404415735007;11928.522859085626;1815.5188874948149;1818.3090425638304;1832.6059897122182;1878.915448804807;1885.8585606079598;80178.99999999942;98674.50662746713;111590;112697.43651281773;130087.34956325158;10963.50664220112;11104.722381940355;11527.654170374903;11580;11765.941170294094;14037.368318425695;14325.64185895361;14336.426542938374;14343.497977570734;14384;8274.255317021376;8614.698485552975;8620.827583448296;8762.956185219033;8777.385583009256;7733.188015258429;8372.246497815226;8619.439736417113;8766.474011559327;8794.516301603473;4392.07766369066;4489.887752806206;4497.145542346943;4573.222552166139;4589.1051245007575;1898.411492437349;1908.5610309628862;1962.1170473287077;1964.1750464804816;1968.9803101969082;761.2235519769914;862.5945805471418;936.6347124621403;937.7889974755625;973.0852998181687;19679.60640787176;20094.693844900034;20231.291904783262;20547.8972605136;20994.685079723815;914.4376208631644;916.7708072981754;946.9715908522755;953.6280850468323;967.2455484721928;10299.639512617023;10323.48382580868;10755.40845253503;10800.729981750519;10818.459077046116;247097;247152;308772.4561377179;315986;316368;136788.31605841906;140862;142398;145055.2747236257;145810;929.5120061967386;966.183574879226;977.2133432586694;986.7977064701719;990.029770824596;13786;18233.997130157437;18241.179146938684;18582.349617763313;18719.344822931133;48593.7851553714;48921.75539122282;52988.67528311669;53556.1966570494;53839.46160538345;49018.754906225244;78443.60778196073;78681.21318786738;78887;81599;6335.556511044183;6662.500312476681;7902.24928631779;8213.973253343333;8244.422890397615;11405.600803971822;12758.170718903242;12858.807117893051;13235.867641323464;13292.667683307995;11108.500117494894;11497.885021149681;11511.079113671054;11553.884461155281;11637.825432618349;10970.012698857141;11053.28153670009;11108.61119860801;11428.657140285804;11490.54037838477;11245.943770281096;11257.15571332137;11621.418929053345;11693.941530292293;11725.648230553097;7958.204179582227;8115.472494287852;8116.391270654606;8160.632771525177;8249.257566819015;8247.257746802816;10772.084372828398;11485.138614604106;11540.365279909349;11544.249623774427 10:31:37 INFO - PROCESS | 3093 | |3;mootools.html;1364.3860262881694;1516.3858637251913;1546.4896584127237;1751.1156865782787;1898.9905050474745;7681.731139410122;8171.142030086845;8171.959140204291;8179.304759095477;8202.794930126749;6945.2013018502885;8508.36187285953;8510.063892971784;8634.568271586428;8651.74044778656;2350.5886469867746;2371.2530552875874;2372.8457650252763;2376.275236053003;2377.120465427794;2316.4672125411166;2322.1175953137868;2363.7872591466767;2367;2375.2636682628354;2321.129576408847;2352.3060697094397;2355.6231003039516;2358.0685629176464;2360.752121027293;2284.5773531896484;2305.9769402306015;2312.4681323295567;2312.629979203336;2325.860448373103;2306.7001289832383;2313;2313.340697901107;2313.8611683298886;2314.6528020796845;8558.743237702878;8731.869021964676;8738.30093592511;8767.210951014376;8779;5105.591552675777;5129.871753206163;5296.682199068068;5297.894042119175;5301.787928482856;3538.3454060998542;3634.4730014148063;3642.1440961374055;3647.908802279938;3651;1066.5893630952062;1073.473997741109;1093.830456279278;1101.7465982823915;1102.8290614954694;11444.084473242205;11571.479283432265;11581.42092895352;11590.652280431515;11601.303921764636;4421.491528474202;4484.215262329079;4533.546645335506;4595.1728688835665;4604.424446944132;9137.680181193626;9140.725778226664;9152.95423522878;9159.358844880795;9179.17387435134;5211.687298762086;5215.113430716788;5218.63469557135;5220.243064755589;5224.111900976843;4038.3134867072667;4397.296432570837;4401.581849724271;4817.759112044384;4897.045076210176;3500.4049311617086;3742.7380083394446;3743.812809359521;3859.208862183246;3874.4382064600477;1052.6736711619421;1150.3155622404822;1155.6995181252767;1159.930404175752;1164.9941750291193;839.5969934431507;849.8781608277015;900.945893304835;904.2133343990639;939.8637197606308;5603.551715862721;5669.262995810601;5993.861166378461;6035.275766907999;6109.358517355702;772.2624893227068;933.5099072986736;935.8830146231721;940.4921342475124;978.4520668425704;3881.2431575843;3890.358090915024;3924.9018774530296;4033.3546632538655;4054.7769872656695;10232.181425485944;10666.839997400046;10695.037446629685;10722.892771072346;10725.83911241332;8934.687285944961;10589.417582033062;10606.840897386546;10622.36265824053;10639.680809575726;7692.499987500794;8028.799280018046;8040.035195776544;8047;8142.51144931306;5873.559483038704;5932.080527518242;5946.345901950868;6091.238595175603;6097.359777223505;9799.363041402286;10066.69799906004;10095.495225238856;10095.697129086138;10117.19062475015;9222.769430764285;9565.426074435556;9768.07203315684;10221.284510084222;10269.383836969806;6051.818445446467;7440.218777028334;7521.135069467081;7590.0891892970985;7618.4667073306555;7823.295903368724;7980.201979801974;8037.598120093855;8059.274665280153;8064.314533264738;7154.499185057204;7155.642217889189;7186.6047367395295;7277.454190935807;7281.635918204174;7109.928900710926;7132.964335178291;7195.172555156224;7257.2742725727;7305.013823133809;6729.057931889637;6769.593824370553;6865.931340686529;6870.896936545856;6878.828029299308;4809.845637047153;4867.95132048675;4900.5099490052135;4903.5586797187;5022.573081288131;4917.311576379238;4937.975310123426;4958.826441074545;4970.229614409773;5060.240963855451 10:31:37 INFO - PROCESS | 3093 | |4;prototype.html;295.18087307721083;296.7151534526854;307.37295916330686;310.45360166107645;315.8815444208422;4195.930037840354;4668.696534725241;5266.973665131673;5342.317170091418;5356.464353564646;4514.548545145484;4937.234728617061;5157.355330583677;5167.560757335631;5191.662541934781;3554.751167418282;3916.0601455650653;3981.845264873183;4042.2117687051036;4067.125568002879;3373.8481768320485;3515.1915059536323;3853.9979605302683;4062.0347146583013;4063.878083657495;4012.959870401288;4016.3975403689387;4031.919361612766;4035.273650742865;4061.639350817465;3729.347364211252;3829.80850957451;3976.443297938291;3984.2828290907623;4035.778032208227;2876.274235458729;3338.699517043478;3476.9652303476896;3477.8608855645743;3478.4086705260165;5188.6509507527935;5206.245094461282;5210.280607399549;5214.374275086976;5227.8170264040955;3892.961070389288;3980.4525103933506;4454.218312674923;4575.588197062247;4585.747783871886;4460.910781784379;4588.7476188809605;5238.764255608507;5576.884623075402;5607.467290607386;3793.525809273841;3794.146317078663;3811.542614886204;3819.560750513671;3838.5777564467885;5220.504052115005;5602.030923610493;5825.854353641193;5980.581359304851;5987.221661184019;3223.790453620554;3896.3181443247317;3898.3372826619543;3904.2386734586485;3919.3141200289833;3489.842957066938;3964.4251583520227;3995.4006898965386;4001.2197621464115;4018.3972404139026;4645.767711614406;4646.581807637329;4648.1865673507;4665.276882083214;4671.299305104194;4858.82994095205;5484.588655850827;5510.338759348823;5652.123920792284;5662.320521537442;1076.208986394999;1246.246021157205;1294.3851670456459;1329.7168232655533;1339.8124262603244;873.3493547307297;884.8363052835246;900.9054049324858;912.2929729459589;913.4382354851718;4558.316252562142;5169.896602067937;5175.585953123741;5202.2456743771945;5204.193350030751;4948.455669876311;5669.639286571193;5674;5684.971575142098;5692.743826527816;4445.82216711328;4512.684112112186;4722.488803582821;4923.433805112458;5157.819476318386;2443.413580740645;2512.9748702513107;2518.3578187562052;2518.9244322670347;2550.783183429392;4566.246569316026;4685.282536129037;4765.475797662254;4920.926186107211;4943.085529177114;4275.418095304757;4570.702904311209;4570.771461426915;4634.768261586907;4878.731669758197;4035.538321623807;4508.7826286902355;4579.397705306146;4662.347271381941;4956.942868709517;4128.710990230655;4398.582134697199;4563.338315944236;4576.734516414396;4759.262314341283;3913.158670885773;3955.4660120883314;4006.7736567041484;4714.457837348748;4725.810967561259;4166.520850102277;4389.924468505109;4603.079384123255;4952.702837829742;4999.70013793659;3272.625497291096;3333.649966753456;3351.5642966415185;3361.277325375056;3377.3319077928163;4450.688451808472;4464.3973063635995;4480.812584665001;4819.204831202812;5522.282103326703;4259.936100958426;4538.387317712067;5004.649674522749;5530.474604912527;5554.472325129106;4649.395578574764;4898.608111351012;5419.945800542102;5604.747786349705;5608.093248295599;4590.472095709036;4947.678400904074;5121.385433747974;5446.645968012067;5486.7902277168305;5266.877882177278;5532.842191545381;5565.805196818092;5582.487880453731;5623.803166889139;4804.927926081042;5485.451454854483;5485.670859748428;5491.917621235605;5494.615376923577 10:31:37 INFO - PROCESS | 3093 | |5;yui.html;605.0654326516395;683.9794806155817;702.1994925784606;762.4434163060965;790.5770412829136;11061.889381106186;14950.925245373757;15406.69186616267;15619.609509762262;15623.296951637161;10564.630237941674;13038.413271402786;13057.477700891954;13058.216507009562;13107.868921310785;821.48246604639;845.6575087089708;849.6346570974479;850.4727069217063;852.6205838401913;683.7333439958395;700.2087640965727;827.735124760077;842.2167384332569;845.9703910363138;877.0527829943677;880.0715245416084;882.3470631732504;882.8013696918206;883.8541640629321;880.5949263338872;880.7621942075667;881.9559022048936;882.8984666763308;883.0815951410525;523.1316015414432;533.4825219537082;534.9786008559653;539.3446961941261;645.8385403649088;13864.722705545833;14117.294135293194;14128.858711412857;14131.293435328294;14170.92914535431;13613.455461781517;14033;14052.367643455967;14070.788938165935;14147.504837330644;11284.943575282154;11470.541178352856;11655.417229138595;11729.941350293278;11805.291682499077;3183.8248896310693;3310.3875782980226;3750.437434384864;3778.5843557208686;3799.7530160539754;111656;112602.43698781454;112961.43519282433;114890.27664585038;115589;10551;10598.31110977784;10728.678139655824;10786.298890572089;11163.497642606102;21769.782302177093;21860.45348866259;22181.667274990887;22203.77796222017;22225.666615000464;7626.4661473697415;7646.23537646242;7815.140334563193;7843.058832940083;7870.409719271078;4294.205571969195;4320.395144679747;4411.205982923105;4413.271810151353;4420.336949457607;3034.5296479045783;3162.335909459039;3163.984180079085;3170.223295292668;3172.888948886778;656.917885264342;665.4244078871741;669.440204323924;670.8825955457776;673.2526895146384;560.7869009776259;669.1869378704799;673.6930355111069;685.4105469296401;688.2429327739446;7589.43079269057;7863.9213607863185;8018.132456599122;8028.959855200686;9864.506774661239;529.4678847758056;602.697219238575;628.23982980594;662.7647185249264;692.4149094015548;4484.148011877733;4514.841980530666;4544.636429085665;4571.451425828922;4584.426946631671;157637;161709.19145404198;163091;165760;168127.1593642024;131318.34340828235;136507;141654;142963;145143;117616;119039;121217;122213.77786222202;123840.9999999982;8853.424527405698;8865.601047952729;9000.324975626985;9074.954625226832;9558.378705384128;52324.47675523196;56323.43676563182;57185.42814571968;60786.696066519384;61187;56626.43373566212;58900.70549647224;60262;71973.56052879019;83489;12656.244576992727;12745.299008554619;12848.23762890983;13036.348182591022;13241.867581324064;14407.135571865721;14807.185604791839;14950.25248737537;15262.23688815577;15298.77051844201;13952.232627205602;14082.718345632826;14352.138871667734;14446.927765361106;14611.707765844836;9681.9031809681;13658.317084145554;13768;13798.793018104536;14024.509142179975;13442.798358024833;13752.793708094587;13849.792253116413;13850.30748462593;14318;8513.616887239965;8574.52840093776;10679.946600266949;10826.51280692355;10912.890871091187;10469.895301046892;11300.660980170607;11463.770724585629;11489.310641361211;11644.126690498417 10:31:37 INFO - PROCESS | 3093 | __end_tp_report 10:31:37 INFO - PROCESS | 3093 | __start_cc_report 10:31:37 INFO - PROCESS | 3093 | _x_x_mozilla_cycle_collect,1316 10:31:37 INFO - PROCESS | 3093 | __end_cc_report 10:31:37 INFO - PROCESS | 3093 | __startTimestamp1470850297188__endTimestamp 10:31:37 INFO - PROCESS | 3093 | 10:31:37 INFO - PROCESS | 3093 | ------- Summary: start ------- 10:31:37 INFO - PROCESS | 3093 | Number of tests: 6 10:31:37 INFO - PROCESS | 3093 | 10:31:37 INFO - PROCESS | 3093 | [#0] dojo.html Cycles:165 Average:5675.57 Median:5883.79 stddev:650.50 (11.1%) stddev-sans-first:615.45 10:31:37 INFO - PROCESS | 3093 | Values: 2917.1 3289.7 3309.0 3327.4 3375.9 5517.9 5549.8 5638.7 5645.3 5662.4 4683.7 5581.2 5635.3 5688.5 5739.4 5080.8 5083.7 5150.4 5152.2 5293.1 5210.2 5222.0 5234.9 5259.8 5262.5 5084.0 5136.3 5142.4 5142.6 5174.4 5114.4 5123.3 5155.0 5191.9 5196.1 5074.4 5083.5 5126.8 5144.4 5198.0 5614.2 5683.6 5698.9 5706.5 5718.1 6042.5 6138.2 6159.5 6161.4 6163.2 6193.9 6241.7 6256.8 6260.1 6260.5 5778.5 5783.0 5790.6 5792.2 5792.7 6143.5 6223.5 6229.8 6259.9 6269.6 5962.4 6000.5 6050.6 6083.1 6085.0 6186.0 6206.3 6207.2 6212.1 6218.8 6076.1 6096.7 6112.4 6126.2 6166.3 6015.6 6121.4 6126.1 6168.0 6190.1 4805.2 4875.2 4875.6 4896.6 4934.4 4317.8 4352.4 4364.5 4374.7 4427.9 4800.7 5478.0 5572.1 5605.0 5629.3 6132.3 6136.5 6172.4 6189.4 6204.0 5621.3 5627.7 5642.0 5645.2 5681.6 5362.7 5368.8 5369.7 5376.9 5392.2 6187.4 6190.3 6228.5 6269.0 6272.2 6212.2 6224.0 6237.5 6248.9 6269.2 6224.1 6224.7 6257.8 6259.5 6293.0 5993.3 6061.2 6113.4 6120.9 6124.7 6225.2 6228.6 6233.1 6237.9 6250.1 6026.5 6033.2 6038.2 6047.6 6109.3 5780.9 5976.9 6009.1 6023.7 6037.3 5766.7 5883.8 5889.7 5913.0 5924.8 6038.2 6038.4 6045.7 6060.6 6062.4 5691.4 5782.4 5792.6 5845.7 5860.2 10:31:37 INFO - PROCESS | 3093 | 10:31:37 INFO - PROCESS | 3093 | [#1] ext.html Cycles:180 Average:72918.03 Median:20502.04 stddev:161708.34 (788.7%) stddev-sans-first:162070.50 10:31:37 INFO - PROCESS | 3093 | Values: 473.1 485.8 516.1 524.0 535.8 30695.0 39877.0 40440.4 40594.0 43406.8 42846.9 43346.1 43419.8 43483.6 43483.6 8683.7 9686.0 9866.4 9870.5 9879.0 8832.7 12022.5 12290.6 12293.9 12297.8 12084.9 12155.9 12156.7 12169.0 12174.6 12035.8 12115.4 12119.4 12119.8 12130.3 10063.4 11872.3 11956.2 11963.5 12054.9 40148.8 40459.4 40463.8 40476.6 40653.8 3321.4 3467.1 3529.4 3595.5 4090.4 30589.1 31549.7 32003.5 32130.4 32167.5 3200.9 3236.6 3291.1 3330.4 3363.0 517612.0 518206.0 519191.8 519705.0 520024.0 56299.7 56464.9 56581.0 56614.0 56676.1 49754.0 59923.7 60476.7 60608.4 60726.7 16036.3 16160.8 16295.8 16297.5 16321.2 11603.0 11908.0 12576.3 12704.7 12772.3 14772.2 15002.5 15005.2 15016.5 15236.6 6126.9 6185.3 6276.6 6290.7 6292.3 910.4 930.6 983.7 1014.5 1041.3 30012.5 30105.8 30143.7 30461.5 30532.5 1011.5 1035.2 1036.5 1062.3 1101.0 15224.2 15547.8 15564.1 15591.6 15952.4 663807.0 665541.0 665853.0 666723.0 671368.0 58449.0 58855.7 58885.0 59036.4 59059.8 1256.1 1293.2 1293.4 1390.5 1406.3 23680.6 27655.9 31241.0 31845.2 33039.7 53208.0 55625.4 57024.1 57234.7 60438.0 613433.0 618807.0 618817.0 619158.0 620744.9 23357.0 23907.0 25836.4 25931.7 26461.8 40855.6 45089.3 46358.8 47349.0 48358.5 38864.9 39137.0 39389.2 40355.0 40502.8 37408.6 39334.4 39375.6 40384.2 40757.2 38003.7 38537.0 38748.6 38828.4 39028.8 28129.7 29681.3 31016.5 32587.7 34901.0 21623.7 22444.2 24570.0 25030.4 25830.9 10:31:37 INFO - PROCESS | 3093 | 10:31:37 INFO - PROCESS | 3093 | [#2] jquery.html Cycles:180 Average:25966.58 Median:312379.23 stddev:53590.44 (17.2%) stddev-sans-first:53706.26 10:31:37 INFO - PROCESS | 3093 | Values: 352.8 368.6 376.9 380.7 402.8 15043.5 15916.2 20426.8 20431.1 20855.9 18874.9 19839.4 19856.0 20408.0 20427.7 5265.5 5271.3 5671.0 5886.7 5902.4 5670.6 5796.4 5801.4 5801.7 5808.6 5516.4 5761.7 5770.2 5774.1 5824.4 5789.5 5860.4 5878.9 5904.2 5910.2 5615.5 5824.9 5828.6 5829.6 5840.2 10485.8 13122.9 19532.8 24834.4 25119.2 1620.9 2070.2 2104.7 2194.7 2636.2 9666.3 9787.8 9829.4 9926.4 11928.5 1815.5 1818.3 1832.6 1878.9 1885.9 80179.0 98674.5 111590.0 112697.4 130087.3 10963.5 11104.7 11527.7 11580.0 11765.9 14037.4 14325.6 14336.4 14343.5 14384.0 8274.3 8614.7 8620.8 8763.0 8777.4 7733.2 8372.2 8619.4 8766.5 8794.5 4392.1 4489.9 4497.1 4573.2 4589.1 1898.4 1908.6 1962.1 1964.2 1969.0 761.2 862.6 936.6 937.8 973.1 19679.6 20094.7 20231.3 20547.9 20994.7 914.4 916.8 947.0 953.6 967.2 10299.6 10323.5 10755.4 10800.7 10818.5 247097.0 247152.0 308772.5 315986.0 316368.0 136788.3 140862.0 142398.0 145055.3 145810.0 929.5 966.2 977.2 986.8 990.0 13786.0 18234.0 18241.2 18582.3 18719.3 48593.8 48921.8 52988.7 53556.2 53839.5 49018.8 78443.6 78681.2 78887.0 81599.0 6335.6 6662.5 7902.2 8214.0 8244.4 11405.6 12758.2 12858.8 13235.9 13292.7 11108.5 11497.9 11511.1 11553.9 11637.8 10970.0 11053.3 11108.6 11428.7 11490.5 11245.9 11257.2 11621.4 11693.9 11725.6 7958.2 8115.5 8116.4 8160.6 8249.3 8247.3 10772.1 11485.1 11540.4 11544.2 10:31:37 INFO - PROCESS | 3093 | 10:31:37 INFO - PROCESS | 3093 | [#3] mootools.html Cycles:180 Average:5578.48 Median:4948.40 stddev:3105.61 (62.8%) stddev-sans-first:3098.17 10:31:37 INFO - PROCESS | 3093 | Values: 1364.4 1516.4 1546.5 1751.1 1899.0 7681.7 8171.1 8172.0 8179.3 8202.8 6945.2 8508.4 8510.1 8634.6 8651.7 2350.6 2371.3 2372.8 2376.3 2377.1 2316.5 2322.1 2363.8 2367.0 2375.3 2321.1 2352.3 2355.6 2358.1 2360.8 2284.6 2306.0 2312.5 2312.6 2325.9 2306.7 2313.0 2313.3 2313.9 2314.7 8558.7 8731.9 8738.3 8767.2 8779.0 5105.6 5129.9 5296.7 5297.9 5301.8 3538.3 3634.5 3642.1 3647.9 3651.0 1066.6 1073.5 1093.8 1101.7 1102.8 11444.1 11571.5 11581.4 11590.7 11601.3 4421.5 4484.2 4533.5 4595.2 4604.4 9137.7 9140.7 9153.0 9159.4 9179.2 5211.7 5215.1 5218.6 5220.2 5224.1 4038.3 4397.3 4401.6 4817.8 4897.0 3500.4 3742.7 3743.8 3859.2 3874.4 1052.7 1150.3 1155.7 1159.9 1165.0 839.6 849.9 900.9 904.2 939.9 5603.6 5669.3 5993.9 6035.3 6109.4 772.3 933.5 935.9 940.5 978.5 3881.2 3890.4 3924.9 4033.4 4054.8 10232.2 10666.8 10695.0 10722.9 10725.8 8934.7 10589.4 10606.8 10622.4 10639.7 7692.5 8028.8 8040.0 8047.0 8142.5 5873.6 5932.1 5946.3 6091.2 6097.4 9799.4 10066.7 10095.5 10095.7 10117.2 9222.8 9565.4 9768.1 10221.3 10269.4 6051.8 7440.2 7521.1 7590.1 7618.5 7823.3 7980.2 8037.6 8059.3 8064.3 7154.5 7155.6 7186.6 7277.5 7281.6 7109.9 7133.0 7195.2 7257.3 7305.0 6729.1 6769.6 6865.9 6870.9 6878.8 4809.8 4868.0 4900.5 4903.6 5022.6 4917.3 4938.0 4958.8 4970.2 5060.2 10:31:37 INFO - PROCESS | 3093 | 10:31:37 INFO - PROCESS | 3093 | [#4] prototype.html Cycles:180 Average:4241.41 Median:4582.57 stddev:1284.20 (28.0%) stddev-sans-first:1253.18 10:31:37 INFO - PROCESS | 3093 | Values: 295.2 296.7 307.4 310.5 315.9 4195.9 4668.7 5267.0 5342.3 5356.5 4514.5 4937.2 5157.4 5167.6 5191.7 3554.8 3916.1 3981.8 4042.2 4067.1 3373.8 3515.2 3854.0 4062.0 4063.9 4013.0 4016.4 4031.9 4035.3 4061.6 3729.3 3829.8 3976.4 3984.3 4035.8 2876.3 3338.7 3477.0 3477.9 3478.4 5188.7 5206.2 5210.3 5214.4 5227.8 3893.0 3980.5 4454.2 4575.6 4585.7 4460.9 4588.7 5238.8 5576.9 5607.5 3793.5 3794.1 3811.5 3819.6 3838.6 5220.5 5602.0 5825.9 5980.6 5987.2 3223.8 3896.3 3898.3 3904.2 3919.3 3489.8 3964.4 3995.4 4001.2 4018.4 4645.8 4646.6 4648.2 4665.3 4671.3 4858.8 5484.6 5510.3 5652.1 5662.3 1076.2 1246.2 1294.4 1329.7 1339.8 873.3 884.8 900.9 912.3 913.4 4558.3 5169.9 5175.6 5202.2 5204.2 4948.5 5669.6 5674.0 5685.0 5692.7 4445.8 4512.7 4722.5 4923.4 5157.8 2443.4 2513.0 2518.4 2518.9 2550.8 4566.2 4685.3 4765.5 4920.9 4943.1 4275.4 4570.7 4570.8 4634.8 4878.7 4035.5 4508.8 4579.4 4662.3 4956.9 4128.7 4398.6 4563.3 4576.7 4759.3 3913.2 3955.5 4006.8 4714.5 4725.8 4166.5 4389.9 4603.1 4952.7 4999.7 3272.6 3333.6 3351.6 3361.3 3377.3 4450.7 4464.4 4480.8 4819.2 5522.3 4259.9 4538.4 5004.6 5530.5 5554.5 4649.4 4898.6 5419.9 5604.7 5608.1 4590.5 4947.7 5121.4 5446.6 5486.8 5266.9 5532.8 5565.8 5582.5 5623.8 4804.9 5485.5 5485.7 5491.9 5494.6 10:31:37 INFO - PROCESS | 3093 | 10:31:37 INFO - PROCESS | 3093 | [#5] yui.html Cycles:180 Average:24942.99 Median:3171.56 stddev:41625.48 (1312.5%) stddev-sans-first:41702.14 10:31:37 INFO - PROCESS | 3093 | Values: 605.1 684.0 702.2 762.4 790.6 11061.9 14950.9 15406.7 15619.6 15623.3 10564.6 13038.4 13057.5 13058.2 13107.9 821.5 845.7 849.6 850.5 852.6 683.7 700.2 827.7 842.2 846.0 877.1 880.1 882.3 882.8 883.9 880.6 880.8 882.0 882.9 883.1 523.1 533.5 535.0 539.3 645.8 13864.7 14117.3 14128.9 14131.3 14170.9 13613.5 14033.0 14052.4 14070.8 14147.5 11284.9 11470.5 11655.4 11729.9 11805.3 3183.8 3310.4 3750.4 3778.6 3799.8 111656.0 112602.4 112961.4 114890.3 115589.0 10551.0 10598.3 10728.7 10786.3 11163.5 21769.8 21860.5 22181.7 22203.8 22225.7 7626.5 7646.2 7815.1 7843.1 7870.4 4294.2 4320.4 4411.2 4413.3 4420.3 3034.5 3162.3 3164.0 3170.2 3172.9 656.9 665.4 669.4 670.9 673.3 560.8 669.2 673.7 685.4 688.2 7589.4 7863.9 8018.1 8029.0 9864.5 529.5 602.7 628.2 662.8 692.4 4484.1 4514.8 4544.6 4571.5 4584.4 157637.0 161709.2 163091.0 165760.0 168127.2 131318.3 136507.0 141654.0 142963.0 145143.0 117616.0 119039.0 121217.0 122213.8 123841.0 8853.4 8865.6 9000.3 9075.0 9558.4 52324.5 56323.4 57185.4 60786.7 61187.0 56626.4 58900.7 60262.0 71973.6 83489.0 12656.2 12745.3 12848.2 13036.3 13241.9 14407.1 14807.2 14950.3 15262.2 15298.8 13952.2 14082.7 14352.1 14446.9 14611.7 9681.9 13658.3 13768.0 13798.8 14024.5 13442.8 13752.8 13849.8 13850.3 14318.0 8513.6 8574.5 10679.9 10826.5 10912.9 10469.9 11300.7 11463.8 11489.3 11644.1 10:31:37 INFO - PROCESS | 3093 | -------- Summary: end -------- 10:31:37 INFO - PROCESS | 3093 | 10:31:37 INFO - TEST-INFO | 3093: exit 0 10:31:37 INFO - TEST-OK | dromaeo_css | took 1117447ms 10:31:37 INFO - TEST-START | kraken 10:31:37 INFO - operating with platform_type : mac_ 10:31:37 INFO - Initialising browser for kraken test... 10:31:37 INFO - TEST-INFO | started process 3096 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmp3VDwRS/profile http://localhost:51129/getInfo.html) 10:31:40 INFO - PROCESS | 3096 | __metrics Screen width/height:1600/1200 10:31:40 INFO - PROCESS | 3096 | colorDepth:24 10:31:40 INFO - PROCESS | 3096 | Browser inner width/height: 1024/689 10:31:40 INFO - PROCESS | 3096 | __metrics 10:31:40 INFO - PROCESS | 3096 | 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" 10:31:40 INFO - PROCESS | 3096 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:31:41 INFO - TEST-INFO | 3096: exit 0 10:31:41 INFO - Browser initialized. 10:31:41 INFO - Running cycle 1/1 for kraken test... 10:31:41 INFO - TEST-INFO | started process 3097 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmp3VDwRS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:31:53 INFO - PROCESS | 3097 | RSS: Main: 243507200 10:31:53 INFO - PROCESS | 3097 | 10:31:55 INFO - PROCESS | 3097 | Cycle 1(1): loaded http://localhost:51129/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51129/tests/kraken/driver.html?testName=audio-beat-detection) 10:31:56 INFO - PROCESS | 3097 | RSS: Main: 293199872 10:31:56 INFO - PROCESS | 3097 | 10:31:59 INFO - PROCESS | 3097 | Cycle 1(1): loaded http://localhost:51129/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51129/tests/kraken/driver.html?testName=audio-dft) 10:31:59 INFO - PROCESS | 3097 | RSS: Main: 299642880 10:31:59 INFO - PROCESS | 3097 | 10:32:03 INFO - PROCESS | 3097 | Cycle 1(1): loaded http://localhost:51129/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51129/tests/kraken/driver.html?testName=audio-fft) 10:32:04 INFO - PROCESS | 3097 | RSS: Main: 395231232 10:32:04 INFO - PROCESS | 3097 | 10:32:06 INFO - PROCESS | 3097 | Cycle 1(1): loaded http://localhost:51129/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51129/tests/kraken/driver.html?testName=audio-oscillator) 10:32:06 INFO - PROCESS | 3097 | RSS: Main: 298463232 10:32:06 INFO - PROCESS | 3097 | 10:32:08 INFO - PROCESS | 3097 | Cycle 1(1): loaded http://localhost:51129/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51129/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:32:09 INFO - PROCESS | 3097 | RSS: Main: 302112768 10:32:09 INFO - PROCESS | 3097 | 10:32:12 INFO - PROCESS | 3097 | Cycle 1(1): loaded http://localhost:51129/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51129/tests/kraken/driver.html?testName=imaging-darkroom) 10:32:12 INFO - PROCESS | 3097 | RSS: Main: 340328448 10:32:12 INFO - PROCESS | 3097 | 10:32:15 INFO - PROCESS | 3097 | Cycle 1(1): loaded http://localhost:51129/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51129/tests/kraken/driver.html?testName=imaging-desaturate) 10:32:16 INFO - PROCESS | 3097 | RSS: Main: 342667264 10:32:16 INFO - PROCESS | 3097 | 10:32:19 INFO - PROCESS | 3097 | Cycle 1(1): loaded http://localhost:51129/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51129/tests/kraken/driver.html?testName=json-parse-financial) 10:32:19 INFO - PROCESS | 3097 | RSS: Main: 341798912 10:32:19 INFO - PROCESS | 3097 | 10:32:21 INFO - PROCESS | 3097 | Cycle 1(1): loaded http://localhost:51129/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51129/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:32:21 INFO - PROCESS | 3097 | RSS: Main: 314736640 10:32:21 INFO - PROCESS | 3097 | 10:32:23 INFO - PROCESS | 3097 | Cycle 1(1): loaded http://localhost:51129/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51129/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:32:24 INFO - PROCESS | 3097 | RSS: Main: 319107072 10:32:24 INFO - PROCESS | 3097 | 10:32:26 INFO - PROCESS | 3097 | Cycle 1(1): loaded http://localhost:51129/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51129/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:32:27 INFO - PROCESS | 3097 | RSS: Main: 324587520 10:32:27 INFO - PROCESS | 3097 | 10:32:30 INFO - PROCESS | 3097 | Cycle 1(1): loaded http://localhost:51129/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51129/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:32:30 INFO - PROCESS | 3097 | RSS: Main: 331710464 10:32:30 INFO - PROCESS | 3097 | 10:32:33 INFO - PROCESS | 3097 | Cycle 1(1): loaded http://localhost:51129/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51129/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:32:34 INFO - PROCESS | 3097 | RSS: Main: 332918784 10:32:34 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | Cycle 1(1): loaded http://localhost:51129/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51129/tests/kraken/driver.html?testName=ai-astar) 10:32:36 INFO - PROCESS | 3097 | RSS: Main: 376020992 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | __start_tp_report 10:32:36 INFO - PROCESS | 3097 | _x_x_mozilla_page_load 10:32:36 INFO - PROCESS | 3097 | _x_x_mozilla_page_load_details 10:32:36 INFO - PROCESS | 3097 | |i|pagename|runs| 10:32:36 INFO - PROCESS | 3097 | |0;ai-astar;103;112;114;114;113;116;162;168;113;112 10:32:36 INFO - PROCESS | 3097 | |1;audio-beat-detection;146;109;107;168;105;131;143;106;138;107 10:32:36 INFO - PROCESS | 3097 | |2;audio-dft;182;184;186;180;186;182;182;184;184;184 10:32:36 INFO - PROCESS | 3097 | |3;audio-fft;79;58;61;89;57;57;67;62;86;73 10:32:36 INFO - PROCESS | 3097 | |4;audio-oscillator;83;70;102;71;70;68;99;70;65;103 10:32:36 INFO - PROCESS | 3097 | |5;imaging-gaussian-blur;89;86;89;87;89;84;90;86;89;87 10:32:36 INFO - PROCESS | 3097 | |6;imaging-darkroom;94;94;94;94;93;94;93;94;93;94 10:32:36 INFO - PROCESS | 3097 | |7;imaging-desaturate;100;134;133;86;86;84;85;84;85;85 10:32:36 INFO - PROCESS | 3097 | |8;json-parse-financial;55;54;54;54;53;54;52;53;53;52 10:32:36 INFO - PROCESS | 3097 | |9;json-stringify-tinderbox;67;75;78;75;74;69;72;72;71;72 10:32:36 INFO - PROCESS | 3097 | |10;stanford-crypto-aes;83;85;85;83;84;85;83;83;86;87 10:32:36 INFO - PROCESS | 3097 | |11;stanford-crypto-ccm;143;163;152;150;144;157;141;136;157;139 10:32:36 INFO - PROCESS | 3097 | |12;stanford-crypto-pbkdf2;167;166;166;166;167;166;166;171;236;213 10:32:36 INFO - PROCESS | 3097 | |13;stanford-crypto-sha256-iterative;69;69;81;86;91;83;68;67;67;65 10:32:36 INFO - PROCESS | 3097 | __end_tp_report 10:32:36 INFO - PROCESS | 3097 | __start_cc_report 10:32:36 INFO - PROCESS | 3097 | _x_x_mozilla_cycle_collect,2113 10:32:36 INFO - PROCESS | 3097 | __end_cc_report 10:32:36 INFO - PROCESS | 3097 | __startTimestamp1470850356623__endTimestamp 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | ------- Summary: start ------- 10:32:36 INFO - PROCESS | 3097 | Number of tests: 14 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | [#0] ai-astar Cycles:10 Average:122.70 Median:114.00 stddev:22.60 (19.8%) stddev-sans-first:22.82 10:32:36 INFO - PROCESS | 3097 | Values: 103.0 112.0 114.0 114.0 113.0 116.0 162.0 168.0 113.0 112.0 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | [#1] audio-beat-detection Cycles:10 Average:126.00 Median:134.50 stddev:22.30 (16.6%) stddev-sans-first:22.44 10:32:36 INFO - PROCESS | 3097 | Values: 146.0 109.0 107.0 168.0 105.0 131.0 143.0 106.0 138.0 107.0 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.90 (1.0%) stddev-sans-first:1.94 10:32:36 INFO - PROCESS | 3097 | Values: 182.0 184.0 186.0 180.0 186.0 182.0 182.0 184.0 184.0 184.0 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | [#3] audio-fft Cycles:10 Average:68.90 Median:70.00 stddev:12.16 (17.4%) stddev-sans-first:12.34 10:32:36 INFO - PROCESS | 3097 | Values: 79.0 58.0 61.0 89.0 57.0 57.0 67.0 62.0 86.0 73.0 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:70.00 stddev:15.39 (22.0%) stddev-sans-first:16.29 10:32:36 INFO - PROCESS | 3097 | Values: 83.0 70.0 102.0 71.0 70.0 68.0 99.0 70.0 65.0 103.0 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:89.00 stddev:1.90 (2.1%) stddev-sans-first:1.94 10:32:36 INFO - PROCESS | 3097 | Values: 89.0 86.0 89.0 87.0 89.0 84.0 90.0 86.0 89.0 87.0 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 10:32:36 INFO - PROCESS | 3097 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 93.0 94.0 93.0 94.0 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:85.00 stddev:20.22 (23.8%) stddev-sans-first:21.40 10:32:36 INFO - PROCESS | 3097 | Values: 100.0 134.0 133.0 86.0 86.0 84.0 85.0 84.0 85.0 85.0 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:54.00 stddev:0.97 (1.8%) stddev-sans-first:0.83 10:32:36 INFO - PROCESS | 3097 | Values: 55.0 54.0 54.0 54.0 53.0 54.0 52.0 53.0 53.0 52.0 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:73.00 stddev:3.17 (4.3%) stddev-sans-first:2.67 10:32:36 INFO - PROCESS | 3097 | Values: 67.0 75.0 78.0 75.0 74.0 69.0 72.0 72.0 71.0 72.0 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.00 stddev:1.43 (1.7%) stddev-sans-first:1.42 10:32:36 INFO - PROCESS | 3097 | Values: 83.0 85.0 85.0 83.0 84.0 85.0 83.0 83.0 86.0 87.0 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | [#11] stanford-crypto-ccm Cycles:10 Average:148.20 Median:151.00 stddev:8.95 (5.9%) stddev-sans-first:9.30 10:32:36 INFO - PROCESS | 3097 | Values: 143.0 163.0 152.0 150.0 144.0 157.0 141.0 136.0 157.0 139.0 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.40 Median:167.00 stddev:24.94 (14.9%) stddev-sans-first:26.11 10:32:36 INFO - PROCESS | 3097 | Values: 167.0 166.0 166.0 166.0 167.0 166.0 166.0 171.0 236.0 213.0 10:32:36 INFO - PROCESS | 3097 | 10:32:36 INFO - PROCESS | 3097 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.60 Median:75.00 stddev:9.57 (12.8%) stddev-sans-first:9.93 10:32:36 INFO - PROCESS | 3097 | Values: 69.0 69.0 81.0 86.0 91.0 83.0 68.0 67.0 67.0 65.0 10:32:36 INFO - PROCESS | 3097 | -------- Summary: end -------- 10:32:36 INFO - PROCESS | 3097 | 10:32:37 INFO - TEST-INFO | 3097: exit 0 10:32:37 INFO - TEST-OK | kraken | took 59595ms 10:32:37 INFO - SUITE-END | took 1177s 10:32:37 INFO - Completed test suite (00:19:37) 10:32:37 INFO - javascript benchmark 10:32:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2917.1394438640614, 3289.67103289671, 3308.9669103308975, 3327.3678001179787, 3375.8818441354556, 5517.862053448655, 5549.778008879645, 5638.661680299175, 5645.322561292641, 5662.405447428017, 4683.695559788612, 5581.246531718217, 5635.267415236013, 5688.516476099526, 5739.426057394258, 5080.77136528857, 5083.694978301295, 5150.407703114152, 5152.227165925104, 5293.073712100387, 5210.16637338026, 5222.033923724104, 5234.92147617786, 5259.815906443275, 5262.500062494057, 5084.008618319429, 5136.280920671072, 5142.357205349332, 5142.56288215504, 5174.379074511083, 5114.43741188469, 5123.2827404163445, 5154.969006198775, 5191.922121168185, 5196.090684130299, 5074.416442109168, 5083.517065878736, 5126.794928202868, 5144.382674079097, 5197.986392653434, 5614.185943038277, 5683.602147849652, 5698.886022279531, 5706.543476521868, 5718.0851063830005, 6042.546808989343, 6138.232720909887, 6159.538034647375, 6161.44546990773, 6163.167972323724, 6193.938060619381, 6241.65670888101, 6256.78101266458, 6260.060990851363, 6260.5304602154565, 5778.508826749731, 5782.97108514459, 5790.594658373958, 5792.160136780143, 5792.710364481759, 6143.542582966849, 6223.471004964629, 6229.844253893598, 6259.874802504016, 6269.62382257066, 5962.433568811043, 6000.519958403404, 6050.57645964787, 6083.087536869505, 6084.969575152096, 6186.0102383618405, 6206.317305096436, 6207.224096987877, 6212.06818977157, 6218.844528886814, 6076.0885867119405, 6096.664683442365, 6112.362237678657, 6126.153079032661, 6166.290876549165, 6015.578909476295, 6121.4490695837585, 6126.08842927457, 6167.982282923357, 6190.102435146879, 4805.183118869801, 4875.1712208923855, 4875.585575226075, 4896.583790377857, 4934.358533390637, 4317.784110794448, 4352.368906508538, 4364.476262848479, 4374.737515749065, 4427.867163985085, 4800.687955282895, 5478.04134276508, 5572.136318870582, 5604.97197514018, 5629.324481062299, 6132.324915438597, 6136.509079273647, 6172.351903050205, 6189.35011823752, 6204.0, 5621.269234999506, 5627.71861406928, 5641.971790141023, 5645.2096706461125, 5681.630694004876, 5362.65142765719, 5368.785248590091, 5369.731513424314, 5376.865578360573, 5392.218128371364, 6187.3503282154725, 6190.288116866528, 6228.532860035425, 6268.968655156695, 6272.247330320209, 6212.192414986023, 6224.0, 6237.500999919905, 6248.937510625017, 6269.247690277196, 6224.128621993015, 6224.657643829632, 6257.812265632038, 6259.530535209787, 6293.0, 5993.280806303272, 6061.242344706911, 6113.419225173601, 6120.8775824484155, 6124.724387402489, 6225.159603453476, 6228.56400051992, 6233.096201050732, 6237.937620623918, 6250.062490626442, 6026.457618814327, 6033.18551995475, 6038.154658347923, 6047.606905551125, 6109.327973922954, 5780.855478613068, 5976.9103463448955, 6009.06859436788, 6023.698815059317, 6037.305709843425, 5766.740496677576, 5883.794067207627, 5889.676067816311, 5912.994790885646, 5924.763009479572, 6038.215032045806, 6038.396160383926, 6045.697715114315, 6060.636361818305, 6062.393760623902, 5691.430856914276, 5782.421757824184, 5792.623479473989, 5845.678487683218, 5860.15027820955], "value": 5632.109690502968, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.122358025862, 485.8469582081644, 516.0633450287728, 523.9554637855782, 535.7803300646735, 30695.0, 39877.0, 40440.39339409911, 40594.0, 43406.78296608521, 42846.928826779345, 43346.13307733851, 43419.78290108553, 43483.56516434834, 43483.56516434834, 8683.652653893836, 9685.95157024214, 9866.408015519057, 9870.45712485813, 9878.950605246964, 8832.735017949439, 12022.51909923602, 12290.631281061582, 12293.877061229363, 12297.815532767016, 12084.879151208463, 12155.878441215564, 12156.696082597962, 12168.965637920788, 12174.573889913854, 12035.819462708067, 12115.39423028845, 12119.394030298537, 12119.818202726921, 12130.332831694253, 10063.396196228177, 11872.287662740264, 11956.222845515014, 11963.521459141624, 12054.879451205463, 40148.799256003535, 40459.39310910339, 40463.7976810117, 40476.59523404758, 40653.78038658845, 3321.385543674428, 3467.098554375855, 3529.4000019996406, 3595.460680897886, 4090.406891000818, 30589.08232753021, 31549.684503154906, 32003.51994720081, 32130.357392852013, 32167.51748723771, 3200.8719651213923, 3236.6277878044093, 3291.078498020558, 3330.3505816365578, 3363.0, 517612.0, 518206.0, 519191.84848909185, 519705.0, 520023.9999999962, 56299.71850140723, 56464.870702585715, 56581.0, 56614.0, 56676.14985775216, 49754.0, 59923.70038149868, 60476.697616511636, 60608.393916060275, 60726.696366517885, 16036.278367473491, 16160.838391615933, 16295.755563666555, 16297.511074667778, 16321.183940802912, 11603.0, 11908.047356211482, 12576.308303043237, 12704.682382940315, 12772.297523636289, 14772.187529685756, 15002.474913377979, 15005.249737513299, 15016.47442339513, 15236.619084522976, 6126.877462450726, 6185.319614842364, 6276.623402595859, 6290.685465726695, 6292.276388215322, 910.41733290694, 930.6324002019164, 983.6901376066517, 1014.5028935821394, 1041.250299784154, 30012.54981175284, 30105.849470752943, 30143.69856301453, 30461.543076853864, 30532.54201186984, 1011.5043628622069, 1035.2494441529982, 1036.549101141006, 1062.3360399750156, 1100.9889901101046, 15224.238788060553, 15547.766783498257, 15564.143972081418, 15591.610209744847, 15952.441664541686, 663807.0, 665541.0, 665853.0, 666723.0, 671368.0, 58449.0, 58855.705721471975, 58885.0, 59036.40963590395, 59059.81880362455, 1256.114439320277, 1293.198217105382, 1293.3985696651102, 1390.4507719450962, 1406.296851574213, 23680.644790328504, 27655.86172069207, 31241.0, 31845.20386990344, 33039.66960330366, 53208.0, 55625.44374556364, 57024.1446378313, 57234.7138264306, 60438.0, 613433.0, 618807.0, 618817.0, 619158.0, 620744.8962755158, 23357.0, 23907.0, 25836.35409114712, 25931.740682592932, 26461.809218585597, 40855.57005504793, 45089.32366014578, 46358.768206158755, 47349.0, 48358.516414836806, 38864.919183485326, 39137.0, 39389.16735800991, 40355.0, 40502.79748601356, 37408.625913740514, 39334.40998385084, 39375.606243937196, 40384.1923161541, 40757.1848563033, 38003.71977681348, 38537.0, 38748.6125138745, 38828.41757373585, 39028.80485597554, 28129.71870281271, 29681.25796855096, 31016.53475197919, 32587.674123258465, 34901.0, 21623.675644865656, 22444.214452494085, 24570.017199311827, 25030.37424064413, 25830.87084564565], "value": 20240.59509868003, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.75131032622005, 368.5724559510967, 376.9491118698975, 380.70685572109477, 402.79256183065723, 15043.473478428243, 15916.204189790522, 20426.795732042676, 20431.080601372938, 20855.895720521414, 18874.905625471853, 19839.40481785545, 19856.007199639997, 20408.0, 20427.693584596243, 5265.526102650771, 5271.262023316739, 5670.971645141769, 5886.705664716768, 5902.439268269508, 5670.6030577859565, 5796.44933731297, 5801.419858014207, 5801.680907550083, 5808.622439541437, 5516.393196748354, 5761.683107429089, 5770.2210201622665, 5774.133879918004, 5824.359320474744, 5789.536837053026, 5860.355360910296, 5878.911816322759, 5904.2324497815225, 5910.202122713422, 5615.466530679579, 5824.854378640525, 5828.621139625953, 5829.621074630136, 5840.15317778924, 10485.842712359321, 13122.934385328108, 19532.80467195324, 24834.37914052145, 25119.24642260735, 1620.9189540522927, 2070.244360808309, 2104.663253879372, 2194.7366316042016, 2636.2126080598387, 9666.275029372826, 9787.755306117333, 9829.410235385829, 9926.404415735007, 11928.522859085626, 1815.5188874948149, 1818.3090425638304, 1832.6059897122182, 1878.915448804807, 1885.8585606079598, 80178.99999999942, 98674.50662746713, 111590.0, 112697.43651281773, 130087.34956325158, 10963.50664220112, 11104.722381940355, 11527.654170374903, 11580.0, 11765.941170294094, 14037.368318425695, 14325.64185895361, 14336.426542938374, 14343.497977570734, 14384.0, 8274.255317021376, 8614.698485552975, 8620.827583448296, 8762.956185219033, 8777.385583009256, 7733.188015258429, 8372.246497815226, 8619.439736417113, 8766.474011559327, 8794.516301603473, 4392.07766369066, 4489.887752806206, 4497.145542346943, 4573.222552166139, 4589.1051245007575, 1898.411492437349, 1908.5610309628862, 1962.1170473287077, 1964.1750464804816, 1968.9803101969082, 761.2235519769914, 862.5945805471418, 936.6347124621403, 937.7889974755625, 973.0852998181687, 19679.60640787176, 20094.693844900034, 20231.291904783262, 20547.8972605136, 20994.685079723815, 914.4376208631644, 916.7708072981754, 946.9715908522755, 953.6280850468323, 967.2455484721928, 10299.639512617023, 10323.48382580868, 10755.40845253503, 10800.729981750519, 10818.459077046116, 247097.0, 247152.0, 308772.4561377179, 315986.0, 316368.0, 136788.31605841906, 140862.0, 142398.0, 145055.2747236257, 145810.0, 929.5120061967386, 966.183574879226, 977.2133432586694, 986.7977064701719, 990.029770824596, 13786.0, 18233.997130157437, 18241.179146938684, 18582.349617763313, 18719.344822931133, 48593.7851553714, 48921.75539122282, 52988.67528311669, 53556.1966570494, 53839.46160538345, 49018.754906225244, 78443.60778196073, 78681.21318786738, 78887.0, 81599.0, 6335.556511044183, 6662.500312476681, 7902.24928631779, 8213.973253343333, 8244.422890397615, 11405.600803971822, 12758.170718903242, 12858.807117893051, 13235.867641323464, 13292.667683307995, 11108.500117494894, 11497.885021149681, 11511.079113671054, 11553.884461155281, 11637.825432618349, 10970.012698857141, 11053.28153670009, 11108.61119860801, 11428.657140285804, 11490.54037838477, 11245.943770281096, 11257.15571332137, 11621.418929053345, 11693.941530292293, 11725.648230553097, 7958.204179582227, 8115.472494287852, 8116.391270654606, 8160.632771525177, 8249.257566819015, 8247.257746802816, 10772.084372828398, 11485.138614604106, 11540.365279909349, 11544.249623774427], "value": 9157.401877283308, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.3860262881694, 1516.3858637251913, 1546.4896584127237, 1751.1156865782787, 1898.9905050474745, 7681.731139410122, 8171.142030086845, 8171.959140204291, 8179.304759095477, 8202.794930126749, 6945.2013018502885, 8508.36187285953, 8510.063892971784, 8634.568271586428, 8651.74044778656, 2350.5886469867746, 2371.2530552875874, 2372.8457650252763, 2376.275236053003, 2377.120465427794, 2316.4672125411166, 2322.1175953137868, 2363.7872591466767, 2367.0, 2375.2636682628354, 2321.129576408847, 2352.3060697094397, 2355.6231003039516, 2358.0685629176464, 2360.752121027293, 2284.5773531896484, 2305.9769402306015, 2312.4681323295567, 2312.629979203336, 2325.860448373103, 2306.7001289832383, 2313.0, 2313.340697901107, 2313.8611683298886, 2314.6528020796845, 8558.743237702878, 8731.869021964676, 8738.30093592511, 8767.210951014376, 8779.0, 5105.591552675777, 5129.871753206163, 5296.682199068068, 5297.894042119175, 5301.787928482856, 3538.3454060998542, 3634.4730014148063, 3642.1440961374055, 3647.908802279938, 3651.0, 1066.5893630952062, 1073.473997741109, 1093.830456279278, 1101.7465982823915, 1102.8290614954694, 11444.084473242205, 11571.479283432265, 11581.42092895352, 11590.652280431515, 11601.303921764636, 4421.491528474202, 4484.215262329079, 4533.546645335506, 4595.1728688835665, 4604.424446944132, 9137.680181193626, 9140.725778226664, 9152.95423522878, 9159.358844880795, 9179.17387435134, 5211.687298762086, 5215.113430716788, 5218.63469557135, 5220.243064755589, 5224.111900976843, 4038.3134867072667, 4397.296432570837, 4401.581849724271, 4817.759112044384, 4897.045076210176, 3500.4049311617086, 3742.7380083394446, 3743.812809359521, 3859.208862183246, 3874.4382064600477, 1052.6736711619421, 1150.3155622404822, 1155.6995181252767, 1159.930404175752, 1164.9941750291193, 839.5969934431507, 849.8781608277015, 900.945893304835, 904.2133343990639, 939.8637197606308, 5603.551715862721, 5669.262995810601, 5993.861166378461, 6035.275766907999, 6109.358517355702, 772.2624893227068, 933.5099072986736, 935.8830146231721, 940.4921342475124, 978.4520668425704, 3881.2431575843, 3890.358090915024, 3924.9018774530296, 4033.3546632538655, 4054.7769872656695, 10232.181425485944, 10666.839997400046, 10695.037446629685, 10722.892771072346, 10725.83911241332, 8934.687285944961, 10589.417582033062, 10606.840897386546, 10622.36265824053, 10639.680809575726, 7692.499987500794, 8028.799280018046, 8040.035195776544, 8047.0, 8142.51144931306, 5873.559483038704, 5932.080527518242, 5946.345901950868, 6091.238595175603, 6097.359777223505, 9799.363041402286, 10066.69799906004, 10095.495225238856, 10095.697129086138, 10117.19062475015, 9222.769430764285, 9565.426074435556, 9768.07203315684, 10221.284510084222, 10269.383836969806, 6051.818445446467, 7440.218777028334, 7521.135069467081, 7590.0891892970985, 7618.4667073306555, 7823.295903368724, 7980.201979801974, 8037.598120093855, 8059.274665280153, 8064.314533264738, 7154.499185057204, 7155.642217889189, 7186.6047367395295, 7277.454190935807, 7281.635918204174, 7109.928900710926, 7132.964335178291, 7195.172555156224, 7257.2742725727, 7305.013823133809, 6729.057931889637, 6769.593824370553, 6865.931340686529, 6870.896936545856, 6878.828029299308, 4809.845637047153, 4867.95132048675, 4900.5099490052135, 4903.5586797187, 5022.573081288131, 4917.311576379238, 4937.975310123426, 4958.826441074545, 4970.229614409773, 5060.240963855451], "value": 4494.476399008784, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.18087307721083, 296.7151534526854, 307.37295916330686, 310.45360166107645, 315.8815444208422, 4195.930037840354, 4668.696534725241, 5266.973665131673, 5342.317170091418, 5356.464353564646, 4514.548545145484, 4937.234728617061, 5157.355330583677, 5167.560757335631, 5191.662541934781, 3554.751167418282, 3916.0601455650653, 3981.845264873183, 4042.2117687051036, 4067.125568002879, 3373.8481768320485, 3515.1915059536323, 3853.9979605302683, 4062.0347146583013, 4063.878083657495, 4012.959870401288, 4016.3975403689387, 4031.919361612766, 4035.273650742865, 4061.639350817465, 3729.347364211252, 3829.80850957451, 3976.443297938291, 3984.2828290907623, 4035.778032208227, 2876.274235458729, 3338.699517043478, 3476.9652303476896, 3477.8608855645743, 3478.4086705260165, 5188.6509507527935, 5206.245094461282, 5210.280607399549, 5214.374275086976, 5227.8170264040955, 3892.961070389288, 3980.4525103933506, 4454.218312674923, 4575.588197062247, 4585.747783871886, 4460.910781784379, 4588.7476188809605, 5238.764255608507, 5576.884623075402, 5607.467290607386, 3793.525809273841, 3794.146317078663, 3811.542614886204, 3819.560750513671, 3838.5777564467885, 5220.504052115005, 5602.030923610493, 5825.854353641193, 5980.581359304851, 5987.221661184019, 3223.790453620554, 3896.3181443247317, 3898.3372826619543, 3904.2386734586485, 3919.3141200289833, 3489.842957066938, 3964.4251583520227, 3995.4006898965386, 4001.2197621464115, 4018.3972404139026, 4645.767711614406, 4646.581807637329, 4648.1865673507, 4665.276882083214, 4671.299305104194, 4858.82994095205, 5484.588655850827, 5510.338759348823, 5652.123920792284, 5662.320521537442, 1076.208986394999, 1246.246021157205, 1294.3851670456459, 1329.7168232655533, 1339.8124262603244, 873.3493547307297, 884.8363052835246, 900.9054049324858, 912.2929729459589, 913.4382354851718, 4558.316252562142, 5169.896602067937, 5175.585953123741, 5202.2456743771945, 5204.193350030751, 4948.455669876311, 5669.639286571193, 5674.0, 5684.971575142098, 5692.743826527816, 4445.82216711328, 4512.684112112186, 4722.488803582821, 4923.433805112458, 5157.819476318386, 2443.413580740645, 2512.9748702513107, 2518.3578187562052, 2518.9244322670347, 2550.783183429392, 4566.246569316026, 4685.282536129037, 4765.475797662254, 4920.926186107211, 4943.085529177114, 4275.418095304757, 4570.702904311209, 4570.771461426915, 4634.768261586907, 4878.731669758197, 4035.538321623807, 4508.7826286902355, 4579.397705306146, 4662.347271381941, 4956.942868709517, 4128.710990230655, 4398.582134697199, 4563.338315944236, 4576.734516414396, 4759.262314341283, 3913.158670885773, 3955.4660120883314, 4006.7736567041484, 4714.457837348748, 4725.810967561259, 4166.520850102277, 4389.924468505109, 4603.079384123255, 4952.702837829742, 4999.70013793659, 3272.625497291096, 3333.649966753456, 3351.5642966415185, 3361.277325375056, 3377.3319077928163, 4450.688451808472, 4464.3973063635995, 4480.812584665001, 4819.204831202812, 5522.282103326703, 4259.936100958426, 4538.387317712067, 5004.649674522749, 5530.474604912527, 5554.472325129106, 4649.395578574764, 4898.608111351012, 5419.945800542102, 5604.747786349705, 5608.093248295599, 4590.472095709036, 4947.678400904074, 5121.385433747974, 5446.645968012067, 5486.7902277168305, 5266.877882177278, 5532.842191545381, 5565.805196818092, 5582.487880453731, 5623.803166889139, 4804.927926081042, 5485.451454854483, 5485.670859748428, 5491.917621235605, 5494.615376923577], "value": 3843.782320294412, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.0654326516395, 683.9794806155817, 702.1994925784606, 762.4434163060965, 790.5770412829136, 11061.889381106186, 14950.925245373757, 15406.69186616267, 15619.609509762262, 15623.296951637161, 10564.630237941674, 13038.413271402786, 13057.477700891954, 13058.216507009562, 13107.868921310785, 821.48246604639, 845.6575087089708, 849.6346570974479, 850.4727069217063, 852.6205838401913, 683.7333439958395, 700.2087640965727, 827.735124760077, 842.2167384332569, 845.9703910363138, 877.0527829943677, 880.0715245416084, 882.3470631732504, 882.8013696918206, 883.8541640629321, 880.5949263338872, 880.7621942075667, 881.9559022048936, 882.8984666763308, 883.0815951410525, 523.1316015414432, 533.4825219537082, 534.9786008559653, 539.3446961941261, 645.8385403649088, 13864.722705545833, 14117.294135293194, 14128.858711412857, 14131.293435328294, 14170.92914535431, 13613.455461781517, 14033.0, 14052.367643455967, 14070.788938165935, 14147.504837330644, 11284.943575282154, 11470.541178352856, 11655.417229138595, 11729.941350293278, 11805.291682499077, 3183.8248896310693, 3310.3875782980226, 3750.437434384864, 3778.5843557208686, 3799.7530160539754, 111656.0, 112602.43698781454, 112961.43519282433, 114890.27664585038, 115589.0, 10551.0, 10598.31110977784, 10728.678139655824, 10786.298890572089, 11163.497642606102, 21769.782302177093, 21860.45348866259, 22181.667274990887, 22203.77796222017, 22225.666615000464, 7626.4661473697415, 7646.23537646242, 7815.140334563193, 7843.058832940083, 7870.409719271078, 4294.205571969195, 4320.395144679747, 4411.205982923105, 4413.271810151353, 4420.336949457607, 3034.5296479045783, 3162.335909459039, 3163.984180079085, 3170.223295292668, 3172.888948886778, 656.917885264342, 665.4244078871741, 669.440204323924, 670.8825955457776, 673.2526895146384, 560.7869009776259, 669.1869378704799, 673.6930355111069, 685.4105469296401, 688.2429327739446, 7589.43079269057, 7863.9213607863185, 8018.132456599122, 8028.959855200686, 9864.506774661239, 529.4678847758056, 602.697219238575, 628.23982980594, 662.7647185249264, 692.4149094015548, 4484.148011877733, 4514.841980530666, 4544.636429085665, 4571.451425828922, 4584.426946631671, 157637.0, 161709.19145404198, 163091.0, 165760.0, 168127.1593642024, 131318.34340828235, 136507.0, 141654.0, 142963.0, 145143.0, 117616.0, 119039.0, 121217.0, 122213.77786222202, 123840.9999999982, 8853.424527405698, 8865.601047952729, 9000.324975626985, 9074.954625226832, 9558.378705384128, 52324.47675523196, 56323.43676563182, 57185.42814571968, 60786.696066519384, 61187.0, 56626.43373566212, 58900.70549647224, 60262.0, 71973.56052879019, 83489.0, 12656.244576992727, 12745.299008554619, 12848.23762890983, 13036.348182591022, 13241.867581324064, 14407.135571865721, 14807.185604791839, 14950.25248737537, 15262.23688815577, 15298.77051844201, 13952.232627205602, 14082.718345632826, 14352.138871667734, 14446.927765361106, 14611.707765844836, 9681.9031809681, 13658.317084145554, 13768.0, 13798.793018104536, 14024.509142179975, 13442.798358024833, 13752.793708094587, 13849.792253116413, 13850.30748462593, 14318.0, 8513.616887239965, 8574.52840093776, 10679.946600266949, 10826.51280692355, 10912.890871091187, 10469.895301046892, 11300.660980170607, 11463.770724585629, 11489.310641361211, 11644.126690498417], "value": 7580.853235084238, "unit": "score"}], "name": "dromaeo_css", "value": 7177.591631211943, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 114.0, 114.0, 113.0, 116.0, 162.0, 168.0, 113.0, 112.0], "value": 122.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 109.0, 107.0, 168.0, 105.0, 131.0, 143.0, 106.0, 138.0, 107.0], "value": 126.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 186.0, 180.0, 186.0, 182.0, 182.0, 184.0, 184.0, 184.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 61.0, 89.0, 57.0, 57.0, 67.0, 62.0, 86.0, 73.0], "value": 68.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 102.0, 71.0, 70.0, 68.0, 99.0, 70.0, 65.0, 103.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 87.0, 89.0, 84.0, 90.0, 86.0, 89.0, 87.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 93.0, 94.0, 93.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 134.0, 133.0, 86.0, 86.0, 84.0, 85.0, 84.0, 85.0, 85.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 53.0, 54.0, 52.0, 53.0, 53.0, 52.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 75.0, 78.0, 75.0, 74.0, 69.0, 72.0, 72.0, 71.0, 72.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 85.0, 83.0, 84.0, 85.0, 83.0, 83.0, 86.0, 87.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 163.0, 152.0, 150.0, 144.0, 157.0, 141.0, 136.0, 157.0, 139.0], "value": 148.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 166.0, 166.0, 167.0, 166.0, 166.0, 171.0, 236.0, 213.0], "value": 178.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 81.0, 86.0, 91.0, 83.0, 68.0, 67.0, 67.0, 65.0], "value": 74.6, "unit": "score"}], "name": "kraken", "value": 1470.1000000000001, "alertThreshold": 2.0}]} 10:32:37 INFO - Return code: 0 10:32:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:32:37 INFO - # TBPL SUCCESS # 10:32:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:32:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:32:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:32:37 INFO - 2016-08-10 10:32:37.925 screenresolution[3101:54929] starting screenresolution argv=screenresolution get 10:32:37 INFO - 2016-08-10 10:32:37.927 screenresolution[3101:54929] Display 0: 1600x1200x32@60 10:32:37 INFO - 2016-08-10 10:32:37.933 screenresolution[3102:54932] starting screenresolution argv=screenresolution list 10:32:37 INFO - Available Modes on Display 0 10:32:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:32:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:32:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:32:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:32:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:32:38 INFO - Intel Iris: 10:32:38 INFO - Chipset Model: Intel Iris 10:32:38 INFO - Type: GPU 10:32:38 INFO - Bus: Built-In 10:32:38 INFO - VRAM (Dynamic, Max): 1536 MB 10:32:38 INFO - Vendor: Intel (0x8086) 10:32:38 INFO - Device ID: 0x0a2e 10:32:38 INFO - Revision ID: 0x0009 10:32:38 INFO - Displays: 10:32:38 INFO - Display: 10:32:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:32:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:32:38 INFO - Display Serial Number: 1600x1200 60 10:32:38 INFO - Main Display: Yes 10:32:38 INFO - Mirror: Off 10:32:38 INFO - Online: Yes 10:32:38 INFO - Rotation: Supported 10:32:38 INFO - Return code: 0 10:32:38 INFO - Running post-action listener: _resource_record_post_action 10:32:38 INFO - [mozharness: 2016-08-10 17:32:38.170786Z] Finished run-tests step (success) 10:32:38 INFO - Running post-run listener: _resource_record_post_run 10:32:38 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 573440; Write bytes: 701096448; Read time: 3; Write time: 1947 10:32:38 INFO - TinderboxPrint: CPU usage
53.6% 10:32:38 INFO - TinderboxPrint: I/O read bytes / time
573,440 / 3 10:32:38 INFO - TinderboxPrint: I/O write bytes / time
701,096,448 / 1,947 10:32:38 INFO - TinderboxPrint: CPU idle
2,250.1 (46.4%) 10:32:38 INFO - TinderboxPrint: CPU system
971.3 (20.0%) 10:32:38 INFO - TinderboxPrint: CPU user
1,628.3 (33.6%) 10:32:38 INFO - TinderboxPrint: Swap in / out
403,365,888 / 0 10:32:38 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401017344; Write bytes: 396684800; Read time: 29957; Write time: 846 10:32:38 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 573440; Write bytes: 292627456; Read time: 3; Write time: 1067 10:32:38 INFO - Running post-run listener: _upload_blobber_files 10:32:38 INFO - Blob upload gear active. 10:32:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:32:38 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 10:32:38 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'] 10:32:38 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 10:32:39 INFO - (blobuploader) - INFO - Open directory for files ... 10:32:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:32:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:32:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:32:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:32:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:32:39 INFO - (blobuploader) - INFO - Done attempting. 10:32:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:32:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:32:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:32:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:32:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:32:40 INFO - (blobuploader) - INFO - Done attempting. 10:32:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:32:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:32:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:32:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:32:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:32:42 INFO - (blobuploader) - INFO - Done attempting. 10:32:42 INFO - (blobuploader) - INFO - Iteration through files over. 10:32:42 INFO - Return code: 0 10:32:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:32:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:32:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a98f94721b85e0ae2dfb5401ac45e6efca4ea5bc763edb2fd40e773b245e249a7e3aca00e4547ced5a263c5a14b1aa0e4662d923cc2059db693e1c2d50922495", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/08057bdb69dc8ff3bf7e2df899cc4d2b31bc5b35431920c1bcf8ca88d8879d033280478fb98cde762b7cd6d5b0d1684035ead091c8b60c4896fe542ff90cd028", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9e3fc8c64079694cb25130674c073eaaf6e9defbfbdb816b1291b0b703c16179441ee67d700e84dd277fd6e1fd1474b23b5bb9d14fc59ea3bef70f7163d6fb53"} 10:32:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:32:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:32:42 INFO - Contents: 10:32:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a98f94721b85e0ae2dfb5401ac45e6efca4ea5bc763edb2fd40e773b245e249a7e3aca00e4547ced5a263c5a14b1aa0e4662d923cc2059db693e1c2d50922495", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/08057bdb69dc8ff3bf7e2df899cc4d2b31bc5b35431920c1bcf8ca88d8879d033280478fb98cde762b7cd6d5b0d1684035ead091c8b60c4896fe542ff90cd028", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9e3fc8c64079694cb25130674c073eaaf6e9defbfbdb816b1291b0b703c16179441ee67d700e84dd277fd6e1fd1474b23b5bb9d14fc59ea3bef70f7163d6fb53"} 10:32:42 INFO - Running post-run listener: copy_logs_to_upload_dir 10:32:42 INFO - Copying logs to upload dir... 10:32:42 INFO - mkdir: /builds/slave/test/build/upload/logs 10:32:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.233753 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-08-10 10:32:43.026183) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 10:32:43.029927) ========= 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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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/a98f94721b85e0ae2dfb5401ac45e6efca4ea5bc763edb2fd40e773b245e249a7e3aca00e4547ced5a263c5a14b1aa0e4662d923cc2059db693e1c2d50922495", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/08057bdb69dc8ff3bf7e2df899cc4d2b31bc5b35431920c1bcf8ca88d8879d033280478fb98cde762b7cd6d5b0d1684035ead091c8b60c4896fe542ff90cd028", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9e3fc8c64079694cb25130674c073eaaf6e9defbfbdb816b1291b0b703c16179441ee67d700e84dd277fd6e1fd1474b23b5bb9d14fc59ea3bef70f7163d6fb53"} build_url:https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010588 build_url: 'https://queue.taskcluster.net/v1/task/GaPyK9McR-6Jt_GRuI7vFw/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/a98f94721b85e0ae2dfb5401ac45e6efca4ea5bc763edb2fd40e773b245e249a7e3aca00e4547ced5a263c5a14b1aa0e4662d923cc2059db693e1c2d50922495", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/08057bdb69dc8ff3bf7e2df899cc4d2b31bc5b35431920c1bcf8ca88d8879d033280478fb98cde762b7cd6d5b0d1684035ead091c8b60c4896fe542ff90cd028", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9e3fc8c64079694cb25130674c073eaaf6e9defbfbdb816b1291b0b703c16179441ee67d700e84dd277fd6e1fd1474b23b5bb9d14fc59ea3bef70f7163d6fb53"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 10:32:43.061835) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 10:32:43.062118) ========= 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.IfoYh8pKkW/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.NdBNBhVbz2/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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.005174 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 10:32:43.110939) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 10:32:43.111248) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 10:32:43.111525) ========= ========= Total master_lag: 0.20 =========