builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0220 starttime: 1468991151.84 results: success (0) buildid: 20160719210919 builduid: 5c6e1c3c593642fda69a422b3bb0c487 revision: 2d1ef80b6820e79ee63799e895503ee291245ea1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 22:05:51.844593) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 22:05:51.845076) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 22:05:51.845534) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 22:05:51.871033) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:05:51.871415) ========= 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.pM9JNbHLF2/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.V8fputjVMm/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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-07-19 22:05:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 60.6M=0s 2016-07-19 22:05:52 (60.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.820564 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:05:52.715837) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:05:52.716239) ========= 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.pM9JNbHLF2/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.V8fputjVMm/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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.032741 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:05:52.770851) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 22:05:52.771227) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 2d1ef80b6820e79ee63799e895503ee291245ea1 --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 2d1ef80b6820e79ee63799e895503ee291245ea1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pM9JNbHLF2/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.V8fputjVMm/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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-07-19 22:05:52,852 truncating revision to first 12 chars 2016-07-19 22:05:52,852 Setting DEBUG logging. 2016-07-19 22:05:52,852 attempt 1/10 2016-07-19 22:05:52,852 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/2d1ef80b6820?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 22:05:53,763 unpacking tar archive at: autoland-2d1ef80b6820/testing/mozharness/ program finished with exit code 0 elapsedTime=1.172202 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 22:05:53.965115) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 22:05:53.965421) ========= script_repo_revision: 2d1ef80b6820e79ee63799e895503ee291245ea1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 22:05:53.966316) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 22:05:53.966589) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 22:05:53.984127) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-07-19 22:05:53.984399) ========= /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.pM9JNbHLF2/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.V8fputjVMm/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 22:05:54 INFO - MultiFileLogger online at 20160719 22:05:54 in /builds/slave/test 22:05:54 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 22:05:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:05:54 INFO - {'append_to_log': False, 22:05:54 INFO - 'base_work_dir': '/builds/slave/test', 22:05:54 INFO - 'blob_upload_branch': 'Autoland', 22:05:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:05:54 INFO - 'branch': 'Autoland', 22:05:54 INFO - 'buildbot_json_path': 'buildprops.json', 22:05:54 INFO - 'config_files': ('talos/mac_config.py',), 22:05:54 INFO - 'default_actions': ('clobber', 22:05:54 INFO - 'read-buildbot-config', 22:05:54 INFO - 'download-and-extract', 22:05:54 INFO - 'populate-webroot', 22:05:54 INFO - 'create-virtualenv', 22:05:54 INFO - 'install', 22:05:54 INFO - 'run-tests'), 22:05:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:05:54 INFO - 'download_minidump_stackwalk': True, 22:05:54 INFO - 'download_symbols': 'ondemand', 22:05:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:05:54 INFO - 'tooltool.py': '/tools/tooltool.py', 22:05:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:05:54 INFO - '/tools/misc-python/virtualenv.py')}, 22:05:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:05:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:05:54 INFO - 'installer_path': 'installer.exe', 22:05:54 INFO - 'log_level': 'info', 22:05:54 INFO - 'log_name': 'talos', 22:05:54 INFO - 'log_to_console': True, 22:05:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:05:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:05:54 INFO - 'opt_config_files': (), 22:05:54 INFO - 'pip_index': False, 22:05:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:05:54 INFO - 'cmd': ('bash', 22:05:54 INFO - '-c', 22:05:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:05:54 INFO - 'enabled': True, 22:05:54 INFO - 'halt_on_failure': False, 22:05:54 INFO - 'name': 'check_screen_resolution'},), 22:05:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:05:54 INFO - 'cmd': ('bash', 22:05:54 INFO - '-c', 22:05:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:05:54 INFO - 'enabled': True, 22:05:54 INFO - 'halt_on_failure': False, 22:05:54 INFO - 'name': 'check_screen_resolution'},), 22:05:54 INFO - 'run_cmd_checks_enabled': True, 22:05:54 INFO - 'sps_profile': False, 22:05:54 INFO - 'sps_profile_interval': 0, 22:05:54 INFO - 'suite': 'dromaeojs', 22:05:54 INFO - 'system_bits': '32', 22:05:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:05:54 INFO - 'title': 't-yosemite-r7-0220', 22:05:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:05:54 INFO - 'use_talos_json': True, 22:05:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:05:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:05:54 INFO - 'work_dir': 'build'} 22:05:54 INFO - [mozharness: 2016-07-20 05:05:54.172927Z] Running clobber step. 22:05:54 INFO - Running pre-action listener: _resource_record_pre_action 22:05:54 INFO - Running main action method: clobber 22:05:54 INFO - rmtree: /builds/slave/test/build 22:05:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:05:56 INFO - Running post-action listener: _resource_record_post_action 22:05:56 INFO - [mozharness: 2016-07-20 05:05:56.097724Z] Finished clobber step (success) 22:05:56 INFO - [mozharness: 2016-07-20 05:05:56.097860Z] Running read-buildbot-config step. 22:05:56 INFO - Running pre-action listener: _resource_record_pre_action 22:05:56 INFO - Running main action method: read_buildbot_config 22:05:56 INFO - Using buildbot properties: 22:05:56 INFO - { 22:05:56 INFO - "project": "", 22:05:56 INFO - "product": "firefox", 22:05:56 INFO - "script_repo_revision": "production", 22:05:56 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 22:05:56 INFO - "repository": "", 22:05:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 22:05:56 INFO - "buildid": "20160719210919", 22:05:56 INFO - "pgo_build": "False", 22:05:56 INFO - "basedir": "/builds/slave/test", 22:05:56 INFO - "buildnumber": 55, 22:05:56 INFO - "slavename": "t-yosemite-r7-0220", 22:05:56 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 22:05:56 INFO - "platform": "yosemite_r7", 22:05:56 INFO - "scheduler": "tests-autoland-macosx64-talos", 22:05:56 INFO - "branch": "autoland", 22:05:56 INFO - "repo_path": "integration/autoland", 22:05:56 INFO - "revision": "2d1ef80b6820e79ee63799e895503ee291245ea1", 22:05:56 INFO - "stage_platform": "macosx64", 22:05:56 INFO - "builduid": "5c6e1c3c593642fda69a422b3bb0c487", 22:05:56 INFO - "slavebuilddir": "test" 22:05:56 INFO - } 22:05:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 22:05:56 INFO - Running post-action listener: _resource_record_post_action 22:05:56 INFO - [mozharness: 2016-07-20 05:05:56.101259Z] Finished read-buildbot-config step (success) 22:05:56 INFO - [mozharness: 2016-07-20 05:05:56.101374Z] Running download-and-extract step. 22:05:56 INFO - Running pre-action listener: _resource_record_pre_action 22:05:56 INFO - Running main action method: download_and_extract 22:05:56 INFO - mkdir: /builds/slave/test/build/tests 22:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:05:56 INFO - https://queue.taskcluster.net/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:05:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 22:05:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 22:05:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 22:05:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 22:05:57 INFO - Downloaded 1199 bytes. 22:05:57 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 22:05:57 INFO - Using the following test package requirements: 22:05:57 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 22:05:57 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:05:57 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 22:05:57 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:05:57 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 22:05:57 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:05:57 INFO - u'jsshell-mac.zip'], 22:05:57 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:05:57 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 22:05:57 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 22:05:57 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:05:57 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 22:05:57 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:05:57 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 22:05:57 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:05:57 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 22:05:57 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:05:57 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 22:05:57 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:05:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:05:57 INFO - https://queue.taskcluster.net/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:05:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:05:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:05:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:05:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:06:00 INFO - Downloaded 18835981 bytes. 22:06:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:06:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:06:03 INFO - Return code: 0 22:06:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:06: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:03 INFO - https://queue.taskcluster.net/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:06:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:06:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:06:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:06:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:06:12 INFO - Downloaded 18835981 bytes. 22:06:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:06:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:06:15 INFO - Return code: 0 22:06: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:15 INFO - https://queue.taskcluster.net/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:06:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:06:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:06:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:06:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:06:16 INFO - Downloaded 14403317 bytes. 22:06:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:06:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:06:17 INFO - Return code: 0 22:06:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:17 INFO - https://queue.taskcluster.net/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:06:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:06:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:06:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 22:06:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 22:06:20 INFO - Downloaded 112931971 bytes. 22:06:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:06:20 INFO - mkdir: /builds/slave/test/properties 22:06:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:06:20 INFO - Writing to file /builds/slave/test/properties/build_url 22:06:20 INFO - Contents: 22:06:20 INFO - build_url:https://queue.taskcluster.net/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:06:21 INFO - Running post-action listener: _resource_record_post_action 22:06:21 INFO - Running post-action listener: set_extra_try_arguments 22:06:21 INFO - [mozharness: 2016-07-20 05:06:21.355863Z] Finished download-and-extract step (success) 22:06:21 INFO - [mozharness: 2016-07-20 05:06:21.356031Z] Running populate-webroot step. 22:06:21 INFO - Running pre-action listener: _resource_record_pre_action 22:06:21 INFO - Running main action method: populate_webroot 22:06:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:06:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:06:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:06:21 INFO - '--tppagecycles', 22:06:21 INFO - '7'], 22:06:21 INFO - 'tests': ['tsvgm']}}, 22:06:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:06:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:06:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:06:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:06:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:06:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:06:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:21 INFO - 'talos_options': ['--disable-e10s'], 22:06:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:06:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:06:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:21 INFO - 'talos_options': ['--disable-e10s'], 22:06:21 INFO - 'tests': ['damp', 'tps']}, 22:06:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:21 INFO - 'tests': ['damp', 'tps']}, 22:06:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:06:21 INFO - 'tests': ['dromaeo_dom']}, 22:06:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:06:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:06:21 INFO - 'tests': ['basic_compositor_video']}, 22:06:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:06:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:06:21 INFO - 'tests': ['a11yr', 22:06:21 INFO - 'ts_paint', 22:06:21 INFO - 'tpaint', 22:06:21 INFO - 'sessionrestore', 22:06:21 INFO - 'sessionrestore_no_auto_restore', 22:06:21 INFO - 'tabpaint']}, 22:06:21 INFO - 'other-e10s': {'tests': ['a11yr', 22:06:21 INFO - 'ts_paint', 22:06:21 INFO - 'tpaint', 22:06:21 INFO - 'sessionrestore', 22:06:21 INFO - 'sessionrestore_no_auto_restore', 22:06:21 INFO - 'tabpaint']}, 22:06:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:06:21 INFO - 'ts_paint', 22:06:21 INFO - 'tpaint', 22:06:21 INFO - 'sessionrestore', 22:06:21 INFO - 'sessionrestore_no_auto_restore', 22:06:21 INFO - 'tabpaint']}, 22:06:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:06:21 INFO - 'ts_paint', 22:06:21 INFO - 'tpaint', 22:06:21 INFO - 'sessionrestore', 22:06:21 INFO - 'sessionrestore_no_auto_restore', 22:06:21 INFO - 'tabpaint']}, 22:06:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:06:21 INFO - 'tests': ['a11yr', 22:06:21 INFO - 'ts_paint', 22:06:21 INFO - 'tpaint', 22:06:21 INFO - 'sessionrestore', 22:06:21 INFO - 'sessionrestore_no_auto_restore', 22:06:21 INFO - 'tabpaint']}, 22:06:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:06:21 INFO - 'tests': ['a11yr', 22:06:21 INFO - 'ts_paint', 22:06:21 INFO - 'tpaint', 22:06:21 INFO - 'sessionrestore', 22:06:21 INFO - 'sessionrestore_no_auto_restore', 22:06:21 INFO - 'tabpaint']}, 22:06:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:06:21 INFO - 'tests': ['tsvgx', 22:06:21 INFO - 'tsvgr_opacity', 22:06:21 INFO - 'tart', 22:06:21 INFO - 'tscrollx', 22:06:21 INFO - 'cart']}, 22:06:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:06:21 INFO - 'tsvgr_opacity', 22:06:21 INFO - 'tart', 22:06:21 INFO - 'tscrollx', 22:06:21 INFO - 'cart']}, 22:06:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:21 INFO - 'talos_options': ['--disable-e10s'], 22:06:21 INFO - 'tests': ['tp5o']}, 22:06:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:21 INFO - 'tests': ['tp5o']}, 22:06:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:21 INFO - 'talos_options': ['--disable-e10s', 22:06:21 INFO - '--xperf_path', 22:06:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:06:21 INFO - 'tests': ['tp5n']}, 22:06:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:21 INFO - 'talos_options': ['--xperf_path', 22:06:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:06:21 INFO - 'tests': ['tp5n']}}, 22:06:21 INFO - 'talos.zip': {'path': '', 22:06:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:06:21 INFO - Running post-action listener: _resource_record_post_action 22:06:21 INFO - [mozharness: 2016-07-20 05:06:21.375173Z] Finished populate-webroot step (success) 22:06:21 INFO - [mozharness: 2016-07-20 05:06:21.375303Z] Running create-virtualenv step. 22:06:21 INFO - Running pre-action listener: _resource_record_pre_action 22:06:21 INFO - Running main action method: create_virtualenv 22:06:21 INFO - Creating virtualenv /builds/slave/test/build/venv 22:06:21 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 22:06:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:06:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:06:21 INFO - Using real prefix '/tools/python27' 22:06:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:06:22 INFO - Installing distribute.............................................................................................................................................................................................done. 22:06:25 INFO - Installing pip.................done. 22:06:25 INFO - Return code: 0 22:06:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:06: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1027dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102872618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac49dd96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102741d30>, '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 0x1024a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102739b20>, '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.pM9JNbHLF2/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.V8fputjVMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 22:06: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', 'pip>=1.5'] in /builds/slave/test/build 22:06: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 pip>=1.5 22:06:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pM9JNbHLF2/Render', 22:06:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:25 INFO - 'HOME': '/Users/cltbld', 22:06:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:25 INFO - 'LOGNAME': 'cltbld', 22:06:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:25 INFO - 'MOZ_NO_REMOTE': '1', 22:06:25 INFO - 'NO_EM_RESTART': '1', 22:06:25 INFO - 'PAGER': '/bin/cat', 22:06:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:25 INFO - 'PWD': '/builds/slave/test', 22:06:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:25 INFO - 'SHELL': '/bin/bash', 22:06:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V8fputjVMm/Listeners', 22:06:25 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 22:06:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:25 INFO - 'USER': 'cltbld', 22:06:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:25 INFO - 'XPC_FLAGS': '0x0', 22:06:25 INFO - 'XPC_SERVICE_NAME': '0', 22:06:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:25 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 22:06:25 INFO - Cleaning up... 22:06:25 INFO - Return code: 0 22:06:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:06: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06: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', '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 0x1027dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102872618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac49dd96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102741d30>, '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 0x1024a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102739b20>, '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.pM9JNbHLF2/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.V8fputjVMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 22:06: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', 'psutil>=3.1.1'] in /builds/slave/test/build 22:06: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 psutil>=3.1.1 22:06:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pM9JNbHLF2/Render', 22:06:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:25 INFO - 'HOME': '/Users/cltbld', 22:06:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:25 INFO - 'LOGNAME': 'cltbld', 22:06:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:25 INFO - 'MOZ_NO_REMOTE': '1', 22:06:25 INFO - 'NO_EM_RESTART': '1', 22:06:25 INFO - 'PAGER': '/bin/cat', 22:06:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:25 INFO - 'PWD': '/builds/slave/test', 22:06:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:25 INFO - 'SHELL': '/bin/bash', 22:06:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V8fputjVMm/Listeners', 22:06:25 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 22:06:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:25 INFO - 'USER': 'cltbld', 22:06:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:25 INFO - 'XPC_FLAGS': '0x0', 22:06:25 INFO - 'XPC_SERVICE_NAME': '0', 22:06:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:25 INFO - Downloading/unpacking psutil>=3.1.1 22:06:25 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 22:06:25 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 22:06:25 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 22:06:25 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 22:06:25 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 22:06:25 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 22:06:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:06:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:06:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:06:28 INFO - Installing collected packages: psutil 22:06:28 INFO - Running setup.py install for psutil 22:06:28 INFO - building 'psutil._psutil_osx' extension 22:06:28 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 22:06:28 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 22:06:28 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 22:06:29 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 22:06:29 INFO - building 'psutil._psutil_posix' extension 22:06:29 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 22:06:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:06:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:06:29 INFO - ^ 22:06:29 INFO - 1 warning generated. 22:06:29 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 22:06:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:06:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:06:29 INFO - Successfully installed psutil 22:06:29 INFO - Cleaning up... 22:06:29 INFO - Return code: 0 22:06:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1027dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102872618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac49dd96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102741d30>, '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 0x1024a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102739b20>, '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.pM9JNbHLF2/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.V8fputjVMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 22:06:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:06:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:06:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pM9JNbHLF2/Render', 22:06:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:29 INFO - 'HOME': '/Users/cltbld', 22:06:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:29 INFO - 'LOGNAME': 'cltbld', 22:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:29 INFO - 'MOZ_NO_REMOTE': '1', 22:06:29 INFO - 'NO_EM_RESTART': '1', 22:06:29 INFO - 'PAGER': '/bin/cat', 22:06:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:29 INFO - 'PWD': '/builds/slave/test', 22:06:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:29 INFO - 'SHELL': '/bin/bash', 22:06:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V8fputjVMm/Listeners', 22:06:29 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 22:06:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:29 INFO - 'USER': 'cltbld', 22:06:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:29 INFO - 'XPC_FLAGS': '0x0', 22:06:29 INFO - 'XPC_SERVICE_NAME': '0', 22:06:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:06:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:06:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:06:32 INFO - Installing collected packages: mozsystemmonitor 22:06:32 INFO - Running setup.py install for mozsystemmonitor 22:06:32 INFO - Successfully installed mozsystemmonitor 22:06:32 INFO - Cleaning up... 22:06:32 INFO - Return code: 0 22:06:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:06:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1027dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102872618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac49dd96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102741d30>, '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 0x1024a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102739b20>, '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.pM9JNbHLF2/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.V8fputjVMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 22:06:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:06:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:06:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pM9JNbHLF2/Render', 22:06:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:32 INFO - 'HOME': '/Users/cltbld', 22:06:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:32 INFO - 'LOGNAME': 'cltbld', 22:06:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:32 INFO - 'MOZ_NO_REMOTE': '1', 22:06:32 INFO - 'NO_EM_RESTART': '1', 22:06:32 INFO - 'PAGER': '/bin/cat', 22:06:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:32 INFO - 'PWD': '/builds/slave/test', 22:06:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:32 INFO - 'SHELL': '/bin/bash', 22:06:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V8fputjVMm/Listeners', 22:06:32 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 22:06:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:32 INFO - 'USER': 'cltbld', 22:06:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:32 INFO - 'XPC_FLAGS': '0x0', 22:06:32 INFO - 'XPC_SERVICE_NAME': '0', 22:06:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:32 INFO - Downloading/unpacking jsonschema==2.5.1 22:06:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:06:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:06:35 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 22:06:35 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 22:06:35 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 22:06:35 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 22:06:35 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 22:06:35 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 22:06:35 INFO - Downloading functools32-3.2.3-2.tar.gz 22:06:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:06:35 INFO - warning: no files found matching '*.txt' 22:06:35 INFO - no previously-included directories found matching 'build' 22:06:35 INFO - no previously-included directories found matching 'dist' 22:06:35 INFO - no previously-included directories found matching '.git*' 22:06:35 INFO - Installing collected packages: jsonschema, functools32 22:06:35 INFO - Running setup.py install for functools32 22:06:35 INFO - warning: no files found matching '*.txt' 22:06:35 INFO - no previously-included directories found matching 'build' 22:06:35 INFO - no previously-included directories found matching 'dist' 22:06:35 INFO - no previously-included directories found matching '.git*' 22:06:35 INFO - Successfully installed jsonschema functools32 22:06:35 INFO - Cleaning up... 22:06:35 INFO - Return code: 0 22:06:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:35 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 0x1027dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102872618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac49dd96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102741d30>, '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 0x1024a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102739b20>, '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.pM9JNbHLF2/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.V8fputjVMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 22:06:35 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 22:06:35 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 22:06:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pM9JNbHLF2/Render', 22:06:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:35 INFO - 'HOME': '/Users/cltbld', 22:06:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:35 INFO - 'LOGNAME': 'cltbld', 22:06:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:35 INFO - 'MOZ_NO_REMOTE': '1', 22:06:35 INFO - 'NO_EM_RESTART': '1', 22:06:35 INFO - 'PAGER': '/bin/cat', 22:06:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:35 INFO - 'PWD': '/builds/slave/test', 22:06:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:35 INFO - 'SHELL': '/bin/bash', 22:06:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V8fputjVMm/Listeners', 22:06:35 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 22:06:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:35 INFO - 'USER': 'cltbld', 22:06:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:35 INFO - 'XPC_FLAGS': '0x0', 22:06:35 INFO - 'XPC_SERVICE_NAME': '0', 22:06:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:36 INFO - Downloading/unpacking blobuploader==1.2.4 22:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:38 INFO - Downloading blobuploader-1.2.4.tar.gz 22:06:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:06:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:06:38 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 22:06:38 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 22:06:38 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 22:06:38 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 22:06:38 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 22:06:38 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 22:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:06:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 INFO - Downloading docopt-0.6.1.tar.gz 22:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:06:39 INFO - Installing collected packages: blobuploader, requests, docopt 22:06:39 INFO - Running setup.py install for blobuploader 22:06:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:06:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:06:40 INFO - Running setup.py install for requests 22:06:40 INFO - Running setup.py install for docopt 22:06:40 INFO - Successfully installed blobuploader requests docopt 22:06:40 INFO - Cleaning up... 22:06:40 INFO - Return code: 0 22:06:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:40 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:40 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:40 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 0x1027dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102872618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac49dd96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102741d30>, '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 0x1024a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102739b20>, '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.pM9JNbHLF2/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.V8fputjVMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 22:06:40 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 22:06:40 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 22:06:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pM9JNbHLF2/Render', 22:06:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:40 INFO - 'HOME': '/Users/cltbld', 22:06:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:40 INFO - 'LOGNAME': 'cltbld', 22:06:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:40 INFO - 'MOZ_NO_REMOTE': '1', 22:06:40 INFO - 'NO_EM_RESTART': '1', 22:06:40 INFO - 'PAGER': '/bin/cat', 22:06:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:40 INFO - 'PWD': '/builds/slave/test', 22:06:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:40 INFO - 'SHELL': '/bin/bash', 22:06:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V8fputjVMm/Listeners', 22:06:40 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 22:06:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:40 INFO - 'USER': 'cltbld', 22:06:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:40 INFO - 'XPC_FLAGS': '0x0', 22:06:40 INFO - 'XPC_SERVICE_NAME': '0', 22:06:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:06:40 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-5HqbRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:06:41 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-yOOQo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:06:41 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-fAYCue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:06:41 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-ca_JYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:06:41 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-sstBaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:06:41 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-dzscgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:06:41 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-6TK3aH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:06:41 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-Z0TRhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:06:41 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-L1csnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:06:41 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-eWV_Cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:06:42 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-06TjQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:06:42 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-B15pHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:06:42 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-7WIAi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:06:42 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-Xq5ozn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:42 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-8H2Vr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:06:42 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-xsimmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:06:42 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-jhj_9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:06:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:06:42 INFO - Running setup.py install for manifestparser 22:06:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:06:42 INFO - Running setup.py install for mozcrash 22:06:43 INFO - Running setup.py install for mozdebug 22:06:43 INFO - Running setup.py install for mozdevice 22:06:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Running setup.py install for mozfile 22:06:43 INFO - Running setup.py install for mozhttpd 22:06:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Running setup.py install for mozinfo 22:06:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Running setup.py install for mozInstall 22:06:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Running setup.py install for mozleak 22:06:44 INFO - Running setup.py install for mozlog 22:06:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:06:44 INFO - Running setup.py install for moznetwork 22:06:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:06:44 INFO - Running setup.py install for mozprocess 22:06:44 INFO - Running setup.py install for mozprofile 22:06:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:06:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:06:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:06:44 INFO - Running setup.py install for mozrunner 22:06:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:06:44 INFO - Running setup.py install for mozscreenshot 22:06:44 INFO - Running setup.py install for moztest 22:06:45 INFO - Running setup.py install for mozversion 22:06:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:06:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:06:45 INFO - Cleaning up... 22:06:45 INFO - Return code: 0 22:06:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:45 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 0x1027dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102872618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac49dd96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102741d30>, '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 0x1024a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102739b20>, '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.pM9JNbHLF2/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.V8fputjVMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 22:06:45 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 22:06:45 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 22:06:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pM9JNbHLF2/Render', 22:06:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:45 INFO - 'HOME': '/Users/cltbld', 22:06:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:45 INFO - 'LOGNAME': 'cltbld', 22:06:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:45 INFO - 'MOZ_NO_REMOTE': '1', 22:06:45 INFO - 'NO_EM_RESTART': '1', 22:06:45 INFO - 'PAGER': '/bin/cat', 22:06:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:45 INFO - 'PWD': '/builds/slave/test', 22:06:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:45 INFO - 'SHELL': '/bin/bash', 22:06:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V8fputjVMm/Listeners', 22:06:45 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 22:06:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:45 INFO - 'USER': 'cltbld', 22:06:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:45 INFO - 'XPC_FLAGS': '0x0', 22:06:45 INFO - 'XPC_SERVICE_NAME': '0', 22:06:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:06:45 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-rKsbvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:06:45 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)) 22:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:06:45 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-uN35IT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:06:45 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)) 22:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:06:45 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-7CoyFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:06:45 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)) 22:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:06:45 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-cWhbPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:06:45 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)) 22:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:06:46 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-ov9tkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:06:46 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-s1AMR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:06:46 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-iA7SnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:06:46 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-lDrzKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:06:46 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-2x7I8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:06:46 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-ovlKrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:06:46 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-gxubOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:06:46 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-wkUrCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:06:46 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-aULJOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:06:46 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-jbZAie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:06:47 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)) 22:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:47 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-bWDgHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:47 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)) 22:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:06:47 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-9b83w4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:06:47 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)) 22:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:06:47 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-5BzqWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:06:47 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)) 22:06:47 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)) 22:06:47 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)) 22:06:47 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)) 22:06:47 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)) 22:06:47 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)) 22:06:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:06:47 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 22:06:47 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 22:06:47 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 22:06:47 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 22:06:47 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 22:06:47 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 22:06:49 INFO - Downloading blessings-1.6.tar.gz 22:06:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:06:49 INFO - Installing collected packages: blessings 22:06:49 INFO - Running setup.py install for blessings 22:06:50 INFO - Successfully installed blessings 22:06:50 INFO - Cleaning up... 22:06:50 INFO - Return code: 0 22:06:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:06:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:06:50 INFO - Reading from file tmpfile_stdout 22:06:50 INFO - Current package versions: 22:06:50 INFO - blessings == 1.6 22:06:50 INFO - blobuploader == 1.2.4 22:06:50 INFO - docopt == 0.6.1 22:06:50 INFO - functools32 == 3.2.3-2 22:06:50 INFO - jsonschema == 2.5.1 22:06:50 INFO - manifestparser == 1.1 22:06:50 INFO - mozInstall == 1.12 22:06:50 INFO - mozcrash == 0.17 22:06:50 INFO - mozdebug == 0.1 22:06:50 INFO - mozdevice == 0.48 22:06:50 INFO - mozfile == 1.2 22:06:50 INFO - mozhttpd == 0.7 22:06:50 INFO - mozinfo == 0.9 22:06:50 INFO - mozleak == 0.1 22:06:50 INFO - mozlog == 3.3 22:06:50 INFO - moznetwork == 0.27 22:06:50 INFO - mozprocess == 0.23 22:06:50 INFO - mozprofile == 0.28 22:06:50 INFO - mozrunner == 6.12 22:06:50 INFO - mozscreenshot == 0.1 22:06:50 INFO - mozsystemmonitor == 0.3 22:06:50 INFO - moztest == 0.7 22:06:50 INFO - mozversion == 1.4 22:06:50 INFO - psutil == 3.1.1 22:06:50 INFO - requests == 1.2.3 22:06:50 INFO - wsgiref == 0.1.2 22:06:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:50 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 0x1027dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102872618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac49dd96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102741d30>, '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 0x1024a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102739b20>, '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.pM9JNbHLF2/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.V8fputjVMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 22:06:50 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 22:06:50 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 22:06:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pM9JNbHLF2/Render', 22:06:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:50 INFO - 'HOME': '/Users/cltbld', 22:06:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:50 INFO - 'LOGNAME': 'cltbld', 22:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:50 INFO - 'MOZ_NO_REMOTE': '1', 22:06:50 INFO - 'NO_EM_RESTART': '1', 22:06:50 INFO - 'PAGER': '/bin/cat', 22:06:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:50 INFO - 'PWD': '/builds/slave/test', 22:06:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:50 INFO - 'SHELL': '/bin/bash', 22:06:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V8fputjVMm/Listeners', 22:06:50 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 22:06:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:50 INFO - 'USER': 'cltbld', 22:06:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:50 INFO - 'XPC_FLAGS': '0x0', 22:06:50 INFO - 'XPC_SERVICE_NAME': '0', 22:06:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:50 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)) 22:06:50 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)) 22:06:50 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)) 22:06:50 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)) 22:06:50 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)) 22:06:50 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)) 22:06:50 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)) 22:06:50 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)) 22:06:50 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)) 22:06:50 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)) 22:06:50 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)) 22:06:50 INFO - Cleaning up... 22:06:50 INFO - Return code: 0 22:06:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:06:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:50 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 0x1027dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102872618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac49dd96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102741d30>, '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 0x1024a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102739b20>, '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.pM9JNbHLF2/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.V8fputjVMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 22:06:50 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 22:06:50 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 22:06:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pM9JNbHLF2/Render', 22:06:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:50 INFO - 'HOME': '/Users/cltbld', 22:06:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:50 INFO - 'LOGNAME': 'cltbld', 22:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:50 INFO - 'MOZ_NO_REMOTE': '1', 22:06:50 INFO - 'NO_EM_RESTART': '1', 22:06:50 INFO - 'PAGER': '/bin/cat', 22:06:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:50 INFO - 'PWD': '/builds/slave/test', 22:06:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:50 INFO - 'SHELL': '/bin/bash', 22:06:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V8fputjVMm/Listeners', 22:06:50 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 22:06:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:50 INFO - 'USER': 'cltbld', 22:06:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:50 INFO - 'XPC_FLAGS': '0x0', 22:06:50 INFO - 'XPC_SERVICE_NAME': '0', 22:06:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:06:50 INFO - Cleaning up... 22:06:50 INFO - Return code: 0 22:06:50 INFO - Running post-action listener: _resource_record_post_action 22:06:50 INFO - Running post-action listener: _start_resource_monitoring 22:06:51 INFO - Starting resource monitoring. 22:06:51 INFO - [mozharness: 2016-07-20 05:06:51.015457Z] Finished create-virtualenv step (success) 22:06:51 INFO - [mozharness: 2016-07-20 05:06:51.016476Z] Running install step. 22:06:51 INFO - Running pre-action listener: _resource_record_pre_action 22:06:51 INFO - Running main action method: install 22:06:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:06:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:06:51 INFO - Reading from file tmpfile_stdout 22:06:51 INFO - Detecting whether we're running mozinstall >=1.0... 22:06:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:06:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:06:51 INFO - Reading from file tmpfile_stdout 22:06:51 INFO - Output received: 22:06:51 INFO - Usage: mozinstall [options] installer 22:06:51 INFO - Options: 22:06:51 INFO - -h, --help show this help message and exit 22:06:51 INFO - -d DEST, --destination=DEST 22:06:51 INFO - Directory to install application into. [default: 22:06:51 INFO - "/builds/slave/test"] 22:06:51 INFO - --app=APP Application being installed. [default: firefox] 22:06:51 INFO - mkdir: /builds/slave/test/build/application 22:06:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:06:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:07:25 INFO - Reading from file tmpfile_stdout 22:07:25 INFO - Output received: 22:07:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:07:25 INFO - Running post-action listener: _resource_record_post_action 22:07:25 INFO - [mozharness: 2016-07-20 05:07:25.646524Z] Finished install step (success) 22:07:25 INFO - [mozharness: 2016-07-20 05:07:25.646671Z] Running run-tests step. 22:07:25 INFO - Running pre-action listener: _resource_record_pre_action 22:07:25 INFO - Running main action method: run_tests 22:07:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:07:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:07:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:07:25 INFO - 2016-07-19 22:07:25.661 screenresolution[1651:9236] starting screenresolution argv=screenresolution get 22:07:25 INFO - 2016-07-19 22:07:25.663 screenresolution[1651:9236] Display 0: 1600x1200x32@60 22:07:25 INFO - 2016-07-19 22:07:25.669 screenresolution[1652:9238] starting screenresolution argv=screenresolution list 22:07:25 INFO - Available Modes on Display 0 22:07:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:07:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:07:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:07:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:07:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:07:25 INFO - Intel Iris: 22:07:25 INFO - Chipset Model: Intel Iris 22:07:25 INFO - Type: GPU 22:07:25 INFO - Bus: Built-In 22:07:25 INFO - VRAM (Dynamic, Max): 1536 MB 22:07:25 INFO - Vendor: Intel (0x8086) 22:07:25 INFO - Device ID: 0x0a2e 22:07:25 INFO - Revision ID: 0x0009 22:07:25 INFO - Displays: 22:07:25 INFO - Display: 22:07:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:07:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:07:25 INFO - Display Serial Number: 1600x1200 60 22:07:25 INFO - Main Display: Yes 22:07:25 INFO - Mirror: Off 22:07:25 INFO - Online: Yes 22:07:25 INFO - Rotation: Supported 22:07:25 INFO - Return code: 0 22:07:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:07:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:07:25 INFO - Python 2.7.3 22:07:25 INFO - Return code: 0 22:07:25 INFO - grabbing minidump binary from tooltool 22:07: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:07:25 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 0x102741d30>, '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 0x1024a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102739b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:07:25 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 22:07:25 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 22:07:25 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 22:07:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:07:26 INFO - Return code: 0 22:07:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:07:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:07:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:07:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:07:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:07:26 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/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--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 22:07:26 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/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0220 --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 22:07:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pM9JNbHLF2/Render', 22:07:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:07:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:07:26 INFO - 'HOME': '/Users/cltbld', 22:07:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:07:26 INFO - 'LOGNAME': 'cltbld', 22:07:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:07:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:07:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:07:26 INFO - 'MOZ_NO_REMOTE': '1', 22:07:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:07:26 INFO - 'NO_EM_RESTART': '1', 22:07:26 INFO - 'PAGER': '/bin/cat', 22:07:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:07:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:26 INFO - 'PWD': '/builds/slave/test', 22:07:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:07:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:07:26 INFO - 'SHELL': '/bin/bash', 22:07:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V8fputjVMm/Listeners', 22:07:26 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 22:07:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:07:26 INFO - 'USER': 'cltbld', 22:07:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:07:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:07:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:07:26 INFO - 'XPC_FLAGS': '0x0', 22:07:26 INFO - 'XPC_SERVICE_NAME': '0', 22:07:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:07:26 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/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--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 22:07:26 INFO - mozversion application_buildid: 20160719210919 22:07:26 INFO - mozversion application_changeset: 2d1ef80b6820e79ee63799e895503ee291245ea1 22:07:26 INFO - mozversion application_display_name: Nightly 22:07:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:07:26 INFO - mozversion application_name: Firefox 22:07:26 INFO - mozversion application_remotingname: firefox 22:07:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:07:26 INFO - mozversion application_vendor: Mozilla 22:07:26 INFO - mozversion application_version: 50.0a1 22:07:26 INFO - mozversion platform_buildid: 20160719210919 22:07:26 INFO - mozversion platform_changeset: 2d1ef80b6820e79ee63799e895503ee291245ea1 22:07:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:07:26 INFO - mozversion platform_version: 50.0a1 22:07:26 INFO - using testdate: 1468991246 22:07:26 INFO - actual date: 1468991246 22:07:26 INFO - starting webserver on 'localhost:49271' 22:07:26 INFO - SUITE-START | Running 2 tests 22:07:26 INFO - TEST-START | dromaeo_css 22:07:26 INFO - operating with platform_type : mac_ 22:07:26 INFO - Initialising browser for dromaeo_css test... 22:07:26 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmpTvUKOd/profile http://localhost:49271/getInfo.html) 22:07:28 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 22:07:28 INFO - PROCESS | 1662 | colorDepth:24 22:07:28 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 22:07:28 INFO - PROCESS | 1662 | __metrics 22:07:28 INFO - PROCESS | 1662 | 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" 22:07:28 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:07:29 INFO - TEST-INFO | 1662: exit 0 22:07:29 INFO - Browser initialized. 22:07:29 INFO - Running cycle 1/1 for dromaeo_css test... 22:07:29 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmpTvUKOd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:07:41 INFO - PROCESS | 1665 | RSS: Main: 244936704 22:07:41 INFO - PROCESS | 1665 | 22:08:32 INFO - PROCESS | 1665 | 1468991312681 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] 22:10:43 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 22:10:45 INFO - PROCESS | 1665 | RSS: Main: 392466432 22:10:45 INFO - PROCESS | 1665 | 22:13:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 22:13:49 INFO - PROCESS | 1665 | RSS: Main: 382373888 22:13:49 INFO - PROCESS | 1665 | 22:16:51 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 22:16:52 INFO - PROCESS | 1665 | RSS: Main: 371777536 22:16:52 INFO - PROCESS | 1665 | 22:19:54 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 22:19:55 INFO - PROCESS | 1665 | RSS: Main: 372326400 22:19:55 INFO - PROCESS | 1665 | 22:22:58 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 22:22:59 INFO - PROCESS | 1665 | RSS: Main: 335540224 22:22:59 INFO - PROCESS | 1665 | 22:26:02 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 22:26:02 INFO - PROCESS | 1665 | RSS: Main: 382550016 22:26:02 INFO - PROCESS | 1665 | 22:26:02 INFO - PROCESS | 1665 | __start_tp_report 22:26:02 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 22:26:02 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 22:26:02 INFO - PROCESS | 1665 | |i|pagename|runs| 22:26:02 INFO - PROCESS | 1665 | |0;dojo.html;3316.7678262521626;3333.8666453341884;3349.832508374581;3360.2103505676178;3421.6065152507454;5534.529564986971;5625.859353516164;5687.829365119042;5712.314522257325;5771.422857714237;5699.544036477082;5702.942970570293;5802.39074897136;5813.73838177282;5840.8247752567495;5133.153029750087;5172.250023746555;5225.477452254782;5234.790608375661;5334.119870221428;5289.047971365153;5320.015797077553;5337.066013447631;5338.27933229015;5367.382750983628;5143.562797162227;5185.585153187736;5205.635605507616;5232.607554433433;5233.633645644806;5230.869228269324;5234.790608375661;5242.475752424765;5243.344581927259;5265.7893684252585;5151.845444636667;5153;5187.429382767892;5222.268882356473;5300.072487314743;5677.914831277534;5776.480116789509;5789.102689083199;5795.971020144914;5828.446297601721;6197.87604247917;6274.717637706314;6277.497800175975;6282.371762823726;6298.118263443122;5120.283160357553;6144.832481828438;6157;6212.0371342441995;6254.280757712831;5751.367349591542;5761.827145185651;5778.5377169826315;5818.941810581882;5824.3593204748295;6225.035119556476;6259.7496100156395;6281.183446151971;6282.089097080898;6286.025666021774;6130.846728831726;6153.138560601519;6156.5998210116195;6221.222347206599;6226.906596401058;6293.402126798039;6304.401081897213;6305.5901366411035;6309.558330916883;6347.079673447417;6075.5747097703625;6266.185395898504;6266.843328916722;6268.498520118471;6284.528660350492;6109.572329936862;6165.907511387333;6206.906896396557;6217.098520714471;6223.471004964629;4910.901781964412;4957.529034741694;4965.379327584052;4982.377202849644;4997.825076122318;4417.624501917308;4424.6902716810155;4444.6666500012625;4450.888727781767;4467.173572889026;5566.888662226733;5708.429157084258;5828.242328497268;5848;5883.764649413975;6187.752489900444;6247.062940558862;6263.592866463665;6292.622442653455;6328.493720502349;5764.942350576524;5786.103154011135;5791.189233507313;5812.796552120655;5860.824175274749;5313.415524292324;5372.946270537323;5392.622516423813;5428.891422171534;5431.809886654027;6241.937580624136;6287.245530536365;6298.842528936813;6318.178636777281;6320.241571011508;6228.03465462854;6276.623402595859;6287.905681414691;6299.464545513682;6327.873442531216;6217.564770466024;6237.96881015592;6255.687215639291;6264.436200741955;6325.841853953688;6227.595206311576;6240.344763799917;6252.656103914328;6253.3121356651645;6259.904516709648;6244.438000579969;6270.811875643738;6277.654728989949;6287.402696743802;6297.685115744286;5961.0462326029365;5980.491658209101;6079.966405710951;6133.6319820810895;6150.846228844314;6093.6417855416485;6133.3866613338305;6146.139540464428;6156.907646385218;6175.382461753788;6042.728077236447;6061.272647282354;6066.878662426815;6088.543359247986;6097.2683278006925;5807.216025836627;5920.141579471038;5948.672822994604;6146.723397447215;6176.382361763967;5938.287405511366;5941.91087133702;5956.344802071855;5963.92649323126;5966.343702192842 22:26:02 INFO - PROCESS | 1665 | |1;ext.html;501.5335737763879;515.5901058658367;523.7695414017833;535.2052202479317;543.5705792423985;30366.84816575917;31015.379692406168;41037;41106.76679699606;41553.16893662129;42717.572824271745;42998.35502467465;43205.91985200372;43230.13539729211;43333;8615.784605384853;9566.856497152548;9733.707988760312;9741.90258097417;9765.365251258645;8595.484270943733;11906.880931190664;11997.880021199764;12009.879901200964;12360.381980900964;11973.46119424628;11992.460339284753;12016.93991530041;12042.494688163979;12050.819237711441;8726.127387261255;8739.169778870997;8741.912580874174;11633.36016519091;11783.351915644636;11571.594994175164;11649.883501164964;11657.242279251821;11660.24208426458;11687.590934317259;39764.403533947014;40102.799486002674;40159.79520614386;40273.99315017118;40282.597174028175;3522.5949015862993;3529.6117427083;3565.5008298838184;3578.624244454321;3600.304503365953;31608;32270;32317.83841080803;32324.6767532324;32473.350532989443;3213.8875139370243;3289.73682105431;3328.4341661917533;3387.8814241501427;3399.847006884696;512402.9999999963;513012;515166;516341;517320;57321.426785732445;58390.41609583935;58484.70757646184;58542.41457585455;58583;60150.39849601448;60175.69912150411;60186.699066504385;60240;60254.69872650696;16283.918580407259;16548.917255413646;16557.337706491606;16560.00639961606;16585.419510317322;11770.76458470843;11978.101642376681;11990;12152.696182595506;12925.418356173996;13650.726985460435;13735.519256826163;17068.914655426644;18508.81491185098;18604.883706977624;5575.4424557543925;7166.462515311268;7167.569945803269;7214.71141154348;7323.608514382357;935.653808090997;1006.8892421833592;1037.7509397744486;1064.2864987370328;1085.1156307609274;29943.550846737315;30004.54993175104;30045.69954300429;30749.38501229963;30771.076867694006;1054.9630762923261;1070.8541860209657;1112.1547623805805;1140.201858698914;1256.9937150314372;14022.948278879125;15548.850317171018;15575.532734017997;15641.530754077396;15809.288582013836;177699;178230;178980;178981;179751;60011.69994150088;61810;62077;62100.68949655223;62164.06753898695;1244.8257243985668;1275.3113318807739;1347.6091933339417;1395.5813256023355;1435.224978511613;29762.851185743933;30634.540481893237;31336.21659458532;31610.36779264448;32333;56770.43229567652;57054.57363565944;57674;57691.711541442026;57782;603679;613116;614732;615225;615587;23375.76624233736;27215.86392068027;27320.316992075357;27359.179224623294;28814;49524.75237623789;49624.51126466212;50055.49944500509;51146.48853511566;51230.74384628202;39296;40012.59987400089;40384.79807600943;40533.79733101316;40803.59196408117;38784.22431551297;38903.61096389113;39352.60647393604;40027.59972400355;40177.39733903935;37732;38261.42607860829;38472.230555388174;38486.80756596199;38533.807330963165;34649.82675086609;35504.3832263541;35627.64372356243;36035.45946810852;36127.096822579646;26032.47935041251;26166.24448541339;26176.476470470865;26325.736742633093;26687.866560667073 22:26:02 INFO - PROCESS | 1665 | |2;jquery.html;343.38705410841635;350.7071595217994;354.79421935277543;360.4035321542847;410.1755471502281;15602.531924042269;17336;20259.088341024653;20933.05801238944;21000.89499552504;20002;20438.08028638711;20778.584428311424;20843.583128337425;20922.26772062978;5527.977324195037;5725.112607545837;6002;6007.128966299884;6008.068749343837;5802.477777000069;5881.85295367615;5905.025670764318;5925;5933.3473317934995;5565.0817615093465;5773.191753154561;5786.276715410574;5873.2658417697785;5876.941230587682;5763.8760441713885;5788.160716696098;5806.361300256968;5806.390329015477;5822.475977162055;5076.390833100015;5230.8692282692855;5565.693886836222;5566.165075238747;5587.441255874421;12940.676483087904;13004.674883127904;13102.08285420021;13160.210387376692;13188.274644894527;2127.9574408511744;2129.8935053247274;2159.7948194921455;2209.3924170852983;2332.0322066342446;9891.653792117313;9892.604295828158;9958.850617240747;10371.481425928749;10395.480225988671;1863.1522657190947;1951.7072439134101;1952.9746882886457;1961.4017724593994;1982.9589465530566;118706;120681;128191.35904320418;128417.35791321077;129654;9326.72019839406;11314.03830674402;11495.770084598262;11500.102483090364;11704.531818727155;12318.199317044542;13449.453312869146;14390;14408.711825763427;14451;6463.2244130703675;6507.381798729113;6870.931290687029;7941.563214023284;8506.106858779864;6404.935950640434;6810.387065164159;8315.916840831514;8319.750407487785;8443.366747493963;3902.297586434412;4532.09945634794;4536.3422303765765;4541.227991241497;4590.67865249436;1886.4246404846654;1934.7581552305962;1940.7865134835156;1942.931997380085;1944.3680803738841;837.7570504553611;854.3336197765752;932.3333816321363;943.4056544376998;945.4469135555639;20355.491112722302;20794.584108317747;20886.89556552238;20921.025005248954;20927.63970341923;828.5898480252193;853.3770347646129;900.5677274907949;904.791897863482;929.0384452092052;10771.138308935266;11012.449377531091;11042.944785276022;11047;11333.43332833355;590480;601246;624387;628615;634832;93688;121630;143158;147889;151761;1000.9149222316184;1013.7313611892854;1023.518946095334;1060.7825395793843;1063.3035361838008;18249.270029198684;18426.447206583824;18428.631427371645;18438.26246950107;18573;54090;58744;59121.40878591159;59149.40850591611;59236.11145832902;87577.37267881974;88044;88168.55915720381;88315.55842220748;90819.18361632862;7059.611721355374;7894.4868583544185;7993.240642138988;8088.9595552021865;8320.958395208227;11724.531018759495;12617.242965422103;12686.429110689856;12930.482780688668;13032.609021729364;11131.944340278247;11206.663800086011;11216;11228.438578071227;11286.20996530235;10705.19711021661;11102.944485277521;11118.444077795915;11166.888331116585;11281.830772538582;9629.614815407585;10704.732381690521;11203.159763017644;11407.087433005505;11604.6518604442;6841.726330946706;7953.443258972048;8029.3175080118845;8109.1485394035135;8231.670733170606;10073.640058591996;10482.37105773656;11045.005949464587;11300.49147788335;11303.095752339957 22:26:02 INFO - PROCESS | 1665 | |3;mootools.html;1504.8570385813348;1542.6066353079966;1728.7234042553187;1879.962400751985;1904.2763749775083;7956.880646790303;8215.26062654361;8567.528785916773;8889.28885689145;8908.64365425383;6810.387065164135;8508.659653613864;8740.606672699729;8746.387752857303;8857.778555536099;2355.140373763577;2381.7975472084895;2384.523095380922;2385.1294277588727;2386.200622791367;2100.7584127825266;2177.314146043999;2316.108298305148;2360.929172124839;2386.96419553706;2332.6734257204007;2357.917472888449;2361.468669549346;2362.917297894574;2366.2428023032635;2321.3384185507134;2366.479374537599;2369.609014512603;2370.8221883358647;2372.478054827935;2328.918487852917;2345.2260753951155;2359.2922123362923;2360.3155085025323;2361.0909799727056;8873.645054197825;9038.367314288002;9040.276777857756;9040.683576074805;9046.004939456654;5247.711375874325;5400.405955344909;5402.8919421611745;5404.567634589223;5407.486288802596;3529.770564913272;3609.657082577151;3654.4701018352457;3654.853805847763;3654.9817250913843;1035.414990530348;1072.201210098474;1091.8089334366455;1096.9725756856062;1097.6706987903597;11885.22746021506;11894.52421903123;11896.286222826659;11905.642830715005;11909.880901190963;4574.222382194968;4676.158291507561;4739.715617062987;4740.5733483986605;4764.404449443819;9732.464714440632;9877.950610246902;9990.950045249727;10000.79998400028;10002.749931251632;5184.611154163453;5258.710770907637;5328.813491527778;5343.893122137535;5345.358556973188;4390.582894625006;4910;4911.287863259808;4913.901721965541;4923.852284431473;3674.191677830873;3886.689064874803;3960.207958408272;4017.5178978522763;4044.757314561077;1284.556827894375;1289.4842063174806;1292.0826213388411;1298.2016060123144;1305.6278960496213;766.8542976834495;883.3992884838243;917.2114376964945;926.1710768861831;932.2728271947891;5782;5806.564507661942;5906.734196961061;5907.734151963172;6028.276606807123;918.912703293186;933.4679232837218;955.1165172215811;956.6986399284203;975.0541974285075;3445.793252404864;3983.2232714620372;4055.452513910643;4060.7766572838186;4061.614146656122;10131.949340253252;10828.404437755842;10846.457677116112;10864.130869530418;11143.387113708663;9166.54167291646;10321.380717156995;10528.105111065644;10629.681109566725;10813.729656758487;8089.595520223966;8356.707515236938;8378.706745263886;8398.160183981676;8399.664013439517;4290.95709042918;5334.866628334323;5823.213866128019;5859.150423188528;6198.81403557894;9802.607895684092;9906.752331191778;10147.949260253652;10176.287659863738;10279.126274266771;10019.949900250453;10379.221558383;10384.584616615251;10405.231110711045;10468.214883883587;7063.71745130189;7447.851042979219;7690.807729806799;7760.262775036363;7784.455088143775;7949.682012719427;8438.915610843813;8584.969803623606;8593;8596.699115531177;7708.421868359784;7835.451518393657;7875.369970402468;7886.408519361185;8072.354211663171;7730.149683534919;7836.725714600189;7851.921480785348;7874.291313781787;8053.758387248392;6933.514653974174;6946.0622815919205;7020.227774944854;7052.33002864727;7070.292970702889;5099.898002040012;5234.528892399702;5257.158854583248;5313;5333.679979201261;4114.876553703393;5267.051930652366;5278.709670968134;5319.973400133129;5356.303680521507 22:26:02 INFO - PROCESS | 1665 | |4;prototype.html;306.080228912119;310.91449851290895;319.61007570763667;320.09572956398165;323.40170684234187;4374.803133858976;4616.930746038808;4915.606751459874;5367.355917289921;5376.892462150755;4401.823927042915;5335.866603334918;5393.568514518839;5403.4603736244;5404.9189262161;3122.9219269518217;3571.5357003589497;4032.8185231664647;4109.541112904921;4115.588441155883;4130.752154870703;4138.1103062841485;4139.337705967046;4158.209940111369;4171.728837625545;3338.8814747059764;3750.8687195948146;3986.368996462321;3988.5811989741096;4019.9900049975013;3971.6822654187595;3985.940210896839;3986.262541429816;4002.079521709994;4007.098402859348;3109.709470569711;3304.8512816923294;3487.3548393547276;3505.371940042044;3515.3145136698354;4906.877328066791;5203.843884683465;5211.843644690627;5231.402399432571;5237.3453318335205;4039.5556488786506;4163.877092180865;4237.131388065439;4241.533431322552;4631.745254011028;5467.715086954562;5593.937151941118;5612.7754889804355;5640.661560306354;5680;3263.526788615661;3730.477733117366;3772.6986538211668;3788.583255841855;3804.714646401531;4476.09054114828;4700.506446823103;5620.932022915633;5831.679257640828;5899.675517846516;3712.814359282025;3865.724310977371;3883.8834834954996;3890.4164375343926;3893.4549163117185;3299.307145499472;3927.3716205407;3939.8030098495533;3942.7974467787026;3952.6047395260243;3808.5810560838286;4342.913141737147;4371.797755617231;4585.862424127282;4613.169629466728;4672.616965347629;5603.411641777636;5612.382637909826;5637.408072152447;5660.8867822643315;1135.0408904475705;1157.976840463186;1317.873218398275;1352.114365090867;1354.2416246901766;737.3474475089585;790.9519886150758;806.4798205157758;845.7251393297203;914.07221670005;4330.891727706769;4892.804287828518;4955.90088198234;5310.628256022119;5362.597805164628;4880.633952453581;5441.809536666197;5507.9877311646205;5750.4473792075905;5824.514748739044;4497.2129877271345;4977.502249774993;5078.4413714491375;5269.841904742864;5307.3896501902;2644.2067379785985;2774.9722502775116;2996.7752418568693;3003.8347890865775;3004.6694863565426;5728.541716662657;5779.156860687595;5925.466707996301;5930.436608522184;5956.936603886748;5477.20580515831;5602.02256397962;5893.233879595625;5919.852003699856;5966.850828729316;5446.891062178814;5530.428350816889;5901.881962360814;5905.4685078343155;5909.87712334655;4968.527989840959;5443.237946687546;5496.203050557727;5662.065759149694;5865.912011319749;4820.469748327611;5111.080005599028;5502.569331973796;5810.564207684356;5820.708964551671;4704.248014911315;5532.751026203911;5814.796482123105;5997.6701281428195;6039.335673075871;3928.941065883957;3932.1545867638597;3947.7039222059034;3949.269385163754;3949.348357520977;5461.672299662033;5923.763049478145;5950.185193516019;5958.702064896651;5980.730867110903;5182.222666600041;5399.514043736082;5455.5908306876345;5698.458646428583;5733.8566535836935;5136.922946155736;5293.682379057115;5648.864879991644;5684.759686978383;5733.627314224562;5095.541401273903;5286.629935904603;5594.41258667834;5603.691796951207;5608.943910561005;5183.740812959412;5656.490915817596;5684.346300175533;5712.85742851423;5737.397573254915;4310.986918074187;5592.468715472024;5597.608167428241;5620.690862002465;5723.370429252695 22:26:02 INFO - PROCESS | 1665 | |5;yui.html;746.0711414289208;748.7042618165825;763.6945221911232;848.6138806842887;849.0175440346792;17752.733708994376;18224;18248.90875545622;18278.725819112726;18290.993995330253;14570.562883113496;16929.899556528846;17178.05520696361;17299.481015569523;17384.52231560319;724.1744411371042;745.7949064007387;899.1817446124029;907.4147175072075;908.5865931001412;846.7586737779734;846.9153084691543;885.1856292211181;916.7891384981458;921.0651189043126;926.874871892296;942.241495596045;942.29328003997;943;946.1673727120125;742.7697413801739;939.5631031570318;939.8073394954018;946.4841661294543;969.2343048991288;634.4511997122502;634.7524465458475;639.9328070552572;705.4039336760429;705.5378726933867;15834.920825395915;15871.444499442465;15880;15895.761563576556;15909.204539773082;12238.571649992296;14749.262536873113;14907.105573665614;14915;14917.925410372878;11655.708607284801;12016.459259333351;12198.39008049594;12322.445489952974;12469.189502682388;3831.2337532493334;3945.881623551298;3964.365701487748;3999.6600288975474;4087.8773636790943;86985.13014869897;111620;112174.87825121645;112657;112930;10076.697699068893;10254.205514035015;10496.370217786805;10522.526486308134;10540.051395374454;23541;23657.645135322982;23668.763312367002;23673.28980130599;23897.761022389903;8169.387295952827;8193.180681931759;8228.950065166991;8242.711505097293;8490.533020683799;4531.048479819209;4576.839810606612;4605.332226827091;4697.177993851063;4734.455537613218;3570.6786389225094;3601.099725068733;3681.502997095358;3682.2451397463456;3683.2633473305445;675.4832553096885;676.3946268090032;678.8845896197658;685.0272612889709;688.0333131949619;595.8272101090722;612.301975747648;645.2579533536377;681.7273090763734;702.880510313248;8970.775730606656;9133.360664753403;9314.906850931404;9645.951770241245;9774.071463210983;552.8203333916493;644.948404127678;650.8698260347854;680.860423613168;701.2391555162603;4358.716683415568;4567.634589232854;4625.309975601111;4773.689710168828;4778.283257511401;129435;149986.25006875113;152121;152373;158676;139390.30304848411;139487.30256348653;139843;141817;145508;73758.6312068447;73881.6305918467;99370;106178;122022;9469.337146399686;9818.500762476226;9818.752248111621;9859.408435493893;10086.94956525242;51695.741521292155;62757;62781;63489;65346.67326663336;83120.58439707763;83905.12664113453;85778.57110714406;88379.72012938806;90108;13059.477620895057;13123;13400.731985360433;13473.797893031417;13582.728345433234;14862.628434289229;15060;15068.472603458826;15324.770128448305;15488.612784680474;13905.37425815861;13934.930325348309;13950.23273719955;14002.57992260234;14470;11539.973037911192;13855.722885542435;13885.305734713022;14043.36804843764;14117;13356.131851429625;13548;13572.592822215349;13696.31518424055;14131.293435328398;11088.944555277172;11206.327620342805;11601.825972610586;11609.767804644029;12121.27272363661;10504.947475262576;10587.20595955291;11613;11624.418779061183;11637 22:26:02 INFO - PROCESS | 1665 | __end_tp_report 22:26:02 INFO - PROCESS | 1665 | __start_cc_report 22:26:02 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1671 22:26:02 INFO - PROCESS | 1665 | __end_cc_report 22:26:02 INFO - PROCESS | 1665 | __startTimestamp1468992362402__endTimestamp 22:26:02 INFO - PROCESS | 1665 | 22:26:02 INFO - PROCESS | 1665 | ------- Summary: start ------- 22:26:02 INFO - PROCESS | 1665 | Number of tests: 6 22:26:02 INFO - PROCESS | 1665 | 22:26:02 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5763.10 Median:5961.05 stddev:638.63 (10.7%) stddev-sans-first:611.07 22:26:02 INFO - PROCESS | 1665 | Values: 3316.8 3333.9 3349.8 3360.2 3421.6 5534.5 5625.9 5687.8 5712.3 5771.4 5699.5 5702.9 5802.4 5813.7 5840.8 5133.2 5172.3 5225.5 5234.8 5334.1 5289.0 5320.0 5337.1 5338.3 5367.4 5143.6 5185.6 5205.6 5232.6 5233.6 5230.9 5234.8 5242.5 5243.3 5265.8 5151.8 5153.0 5187.4 5222.3 5300.1 5677.9 5776.5 5789.1 5796.0 5828.4 6197.9 6274.7 6277.5 6282.4 6298.1 5120.3 6144.8 6157.0 6212.0 6254.3 5751.4 5761.8 5778.5 5818.9 5824.4 6225.0 6259.7 6281.2 6282.1 6286.0 6130.8 6153.1 6156.6 6221.2 6226.9 6293.4 6304.4 6305.6 6309.6 6347.1 6075.6 6266.2 6266.8 6268.5 6284.5 6109.6 6165.9 6206.9 6217.1 6223.5 4910.9 4957.5 4965.4 4982.4 4997.8 4417.6 4424.7 4444.7 4450.9 4467.2 5566.9 5708.4 5828.2 5848.0 5883.8 6187.8 6247.1 6263.6 6292.6 6328.5 5764.9 5786.1 5791.2 5812.8 5860.8 5313.4 5372.9 5392.6 5428.9 5431.8 6241.9 6287.2 6298.8 6318.2 6320.2 6228.0 6276.6 6287.9 6299.5 6327.9 6217.6 6238.0 6255.7 6264.4 6325.8 6227.6 6240.3 6252.7 6253.3 6259.9 6244.4 6270.8 6277.7 6287.4 6297.7 5961.0 5980.5 6080.0 6133.6 6150.8 6093.6 6133.4 6146.1 6156.9 6175.4 6042.7 6061.3 6066.9 6088.5 6097.3 5807.2 5920.1 5948.7 6146.7 6176.4 5938.3 5941.9 5956.3 5963.9 5966.3 22:26:02 INFO - PROCESS | 1665 | 22:26:02 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:59635.95 Median:35566.01 stddev:127006.85 (357.1%) stddev-sans-first:127285.53 22:26:02 INFO - PROCESS | 1665 | Values: 501.5 515.6 523.8 535.2 543.6 30366.8 31015.4 41037.0 41106.8 41553.2 42717.6 42998.4 43205.9 43230.1 43333.0 8615.8 9566.9 9733.7 9741.9 9765.4 8595.5 11906.9 11997.9 12009.9 12360.4 11973.5 11992.5 12016.9 12042.5 12050.8 8726.1 8739.2 8741.9 11633.4 11783.4 11571.6 11649.9 11657.2 11660.2 11687.6 39764.4 40102.8 40159.8 40274.0 40282.6 3522.6 3529.6 3565.5 3578.6 3600.3 31608.0 32270.0 32317.8 32324.7 32473.4 3213.9 3289.7 3328.4 3387.9 3399.8 512403.0 513012.0 515166.0 516341.0 517320.0 57321.4 58390.4 58484.7 58542.4 58583.0 60150.4 60175.7 60186.7 60240.0 60254.7 16283.9 16548.9 16557.3 16560.0 16585.4 11770.8 11978.1 11990.0 12152.7 12925.4 13650.7 13735.5 17068.9 18508.8 18604.9 5575.4 7166.5 7167.6 7214.7 7323.6 935.7 1006.9 1037.8 1064.3 1085.1 29943.6 30004.5 30045.7 30749.4 30771.1 1055.0 1070.9 1112.2 1140.2 1257.0 14022.9 15548.9 15575.5 15641.5 15809.3 177699.0 178230.0 178980.0 178981.0 179751.0 60011.7 61810.0 62077.0 62100.7 62164.1 1244.8 1275.3 1347.6 1395.6 1435.2 29762.9 30634.5 31336.2 31610.4 32333.0 56770.4 57054.6 57674.0 57691.7 57782.0 603679.0 613116.0 614732.0 615225.0 615587.0 23375.8 27215.9 27320.3 27359.2 28814.0 49524.8 49624.5 50055.5 51146.5 51230.7 39296.0 40012.6 40384.8 40533.8 40803.6 38784.2 38903.6 39352.6 40027.6 40177.4 37732.0 38261.4 38472.2 38486.8 38533.8 34649.8 35504.4 35627.6 36035.5 36127.1 26032.5 26166.2 26176.5 26325.7 26687.9 22:26:02 INFO - PROCESS | 1665 | 22:26:02 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:35746.82 Median:2144.84 stddev:103255.00 (4814.1%) stddev-sans-first:103510.44 22:26:02 INFO - PROCESS | 1665 | Values: 343.4 350.7 354.8 360.4 410.2 15602.5 17336.0 20259.1 20933.1 21000.9 20002.0 20438.1 20778.6 20843.6 20922.3 5528.0 5725.1 6002.0 6007.1 6008.1 5802.5 5881.9 5905.0 5925.0 5933.3 5565.1 5773.2 5786.3 5873.3 5876.9 5763.9 5788.2 5806.4 5806.4 5822.5 5076.4 5230.9 5565.7 5566.2 5587.4 12940.7 13004.7 13102.1 13160.2 13188.3 2128.0 2129.9 2159.8 2209.4 2332.0 9891.7 9892.6 9958.9 10371.5 10395.5 1863.2 1951.7 1953.0 1961.4 1983.0 118706.0 120681.0 128191.4 128417.4 129654.0 9326.7 11314.0 11495.8 11500.1 11704.5 12318.2 13449.5 14390.0 14408.7 14451.0 6463.2 6507.4 6870.9 7941.6 8506.1 6404.9 6810.4 8315.9 8319.8 8443.4 3902.3 4532.1 4536.3 4541.2 4590.7 1886.4 1934.8 1940.8 1942.9 1944.4 837.8 854.3 932.3 943.4 945.4 20355.5 20794.6 20886.9 20921.0 20927.6 828.6 853.4 900.6 904.8 929.0 10771.1 11012.4 11042.9 11047.0 11333.4 590480.0 601246.0 624387.0 628615.0 634832.0 93688.0 121630.0 143158.0 147889.0 151761.0 1000.9 1013.7 1023.5 1060.8 1063.3 18249.3 18426.4 18428.6 18438.3 18573.0 54090.0 58744.0 59121.4 59149.4 59236.1 87577.4 88044.0 88168.6 88315.6 90819.2 7059.6 7894.5 7993.2 8089.0 8321.0 11724.5 12617.2 12686.4 12930.5 13032.6 11131.9 11206.7 11216.0 11228.4 11286.2 10705.2 11102.9 11118.4 11166.9 11281.8 9629.6 10704.7 11203.2 11407.1 11604.7 6841.7 7953.4 8029.3 8109.1 8231.7 10073.6 10482.4 11045.0 11300.5 11303.1 22:26:02 INFO - PROCESS | 1665 | 22:26:02 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5753.88 Median:5011.88 stddev:3225.74 (64.4%) stddev-sans-first:3218.99 22:26:02 INFO - PROCESS | 1665 | Values: 1504.9 1542.6 1728.7 1880.0 1904.3 7956.9 8215.3 8567.5 8889.3 8908.6 6810.4 8508.7 8740.6 8746.4 8857.8 2355.1 2381.8 2384.5 2385.1 2386.2 2100.8 2177.3 2316.1 2360.9 2387.0 2332.7 2357.9 2361.5 2362.9 2366.2 2321.3 2366.5 2369.6 2370.8 2372.5 2328.9 2345.2 2359.3 2360.3 2361.1 8873.6 9038.4 9040.3 9040.7 9046.0 5247.7 5400.4 5402.9 5404.6 5407.5 3529.8 3609.7 3654.5 3654.9 3655.0 1035.4 1072.2 1091.8 1097.0 1097.7 11885.2 11894.5 11896.3 11905.6 11909.9 4574.2 4676.2 4739.7 4740.6 4764.4 9732.5 9878.0 9991.0 10000.8 10002.7 5184.6 5258.7 5328.8 5343.9 5345.4 4390.6 4910.0 4911.3 4913.9 4923.9 3674.2 3886.7 3960.2 4017.5 4044.8 1284.6 1289.5 1292.1 1298.2 1305.6 766.9 883.4 917.2 926.2 932.3 5782.0 5806.6 5906.7 5907.7 6028.3 918.9 933.5 955.1 956.7 975.1 3445.8 3983.2 4055.5 4060.8 4061.6 10131.9 10828.4 10846.5 10864.1 11143.4 9166.5 10321.4 10528.1 10629.7 10813.7 8089.6 8356.7 8378.7 8398.2 8399.7 4291.0 5334.9 5823.2 5859.2 6198.8 9802.6 9906.8 10147.9 10176.3 10279.1 10019.9 10379.2 10384.6 10405.2 10468.2 7063.7 7447.9 7690.8 7760.3 7784.5 7949.7 8438.9 8585.0 8593.0 8596.7 7708.4 7835.5 7875.4 7886.4 8072.4 7730.1 7836.7 7851.9 7874.3 8053.8 6933.5 6946.1 7020.2 7052.3 7070.3 5099.9 5234.5 5257.2 5313.0 5333.7 4114.9 5267.1 5278.7 5320.0 5356.3 22:26:02 INFO - PROCESS | 1665 | 22:26:02 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4514.87 Median:5124.00 stddev:1413.09 (27.6%) stddev-sans-first:1381.29 22:26:02 INFO - PROCESS | 1665 | Values: 306.1 310.9 319.6 320.1 323.4 4374.8 4616.9 4915.6 5367.4 5376.9 4401.8 5335.9 5393.6 5403.5 5404.9 3122.9 3571.5 4032.8 4109.5 4115.6 4130.8 4138.1 4139.3 4158.2 4171.7 3338.9 3750.9 3986.4 3988.6 4020.0 3971.7 3985.9 3986.3 4002.1 4007.1 3109.7 3304.9 3487.4 3505.4 3515.3 4906.9 5203.8 5211.8 5231.4 5237.3 4039.6 4163.9 4237.1 4241.5 4631.7 5467.7 5593.9 5612.8 5640.7 5680.0 3263.5 3730.5 3772.7 3788.6 3804.7 4476.1 4700.5 5620.9 5831.7 5899.7 3712.8 3865.7 3883.9 3890.4 3893.5 3299.3 3927.4 3939.8 3942.8 3952.6 3808.6 4342.9 4371.8 4585.9 4613.2 4672.6 5603.4 5612.4 5637.4 5660.9 1135.0 1158.0 1317.9 1352.1 1354.2 737.3 791.0 806.5 845.7 914.1 4330.9 4892.8 4955.9 5310.6 5362.6 4880.6 5441.8 5508.0 5750.4 5824.5 4497.2 4977.5 5078.4 5269.8 5307.4 2644.2 2775.0 2996.8 3003.8 3004.7 5728.5 5779.2 5925.5 5930.4 5956.9 5477.2 5602.0 5893.2 5919.9 5966.9 5446.9 5530.4 5901.9 5905.5 5909.9 4968.5 5443.2 5496.2 5662.1 5865.9 4820.5 5111.1 5502.6 5810.6 5820.7 4704.2 5532.8 5814.8 5997.7 6039.3 3928.9 3932.2 3947.7 3949.3 3949.3 5461.7 5923.8 5950.2 5958.7 5980.7 5182.2 5399.5 5455.6 5698.5 5733.9 5136.9 5293.7 5648.9 5684.8 5733.6 5095.5 5286.6 5594.4 5603.7 5608.9 5183.7 5656.5 5684.3 5712.9 5737.4 4311.0 5592.5 5597.6 5620.7 5723.4 22:26:02 INFO - PROCESS | 1665 | 22:26:02 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:24846.63 Median:3682.75 stddev:39388.14 (1069.5%) stddev-sans-first:39457.07 22:26:02 INFO - PROCESS | 1665 | Values: 746.1 748.7 763.7 848.6 849.0 17752.7 18224.0 18248.9 18278.7 18291.0 14570.6 16929.9 17178.1 17299.5 17384.5 724.2 745.8 899.2 907.4 908.6 846.8 846.9 885.2 916.8 921.1 926.9 942.2 942.3 943.0 946.2 742.8 939.6 939.8 946.5 969.2 634.5 634.8 639.9 705.4 705.5 15834.9 15871.4 15880.0 15895.8 15909.2 12238.6 14749.3 14907.1 14915.0 14917.9 11655.7 12016.5 12198.4 12322.4 12469.2 3831.2 3945.9 3964.4 3999.7 4087.9 86985.1 111620.0 112174.9 112657.0 112930.0 10076.7 10254.2 10496.4 10522.5 10540.1 23541.0 23657.6 23668.8 23673.3 23897.8 8169.4 8193.2 8229.0 8242.7 8490.5 4531.0 4576.8 4605.3 4697.2 4734.5 3570.7 3601.1 3681.5 3682.2 3683.3 675.5 676.4 678.9 685.0 688.0 595.8 612.3 645.3 681.7 702.9 8970.8 9133.4 9314.9 9646.0 9774.1 552.8 644.9 650.9 680.9 701.2 4358.7 4567.6 4625.3 4773.7 4778.3 129435.0 149986.3 152121.0 152373.0 158676.0 139390.3 139487.3 139843.0 141817.0 145508.0 73758.6 73881.6 99370.0 106178.0 122022.0 9469.3 9818.5 9818.8 9859.4 10086.9 51695.7 62757.0 62781.0 63489.0 65346.7 83120.6 83905.1 85778.6 88379.7 90108.0 13059.5 13123.0 13400.7 13473.8 13582.7 14862.6 15060.0 15068.5 15324.8 15488.6 13905.4 13934.9 13950.2 14002.6 14470.0 11540.0 13855.7 13885.3 14043.4 14117.0 13356.1 13548.0 13572.6 13696.3 14131.3 11088.9 11206.3 11601.8 11609.8 12121.3 10504.9 10587.2 11613.0 11624.4 11637.0 22:26:02 INFO - PROCESS | 1665 | -------- Summary: end -------- 22:26:02 INFO - PROCESS | 1665 | 22:26:03 INFO - TEST-INFO | 1665: exit 0 22:26:03 INFO - TEST-OK | dromaeo_css | took 1116864ms 22:26:03 INFO - TEST-START | kraken 22:26:03 INFO - operating with platform_type : mac_ 22:26:03 INFO - Initialising browser for kraken test... 22:26:03 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmp3PCSIz/profile http://localhost:49271/getInfo.html) 22:26:05 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 22:26:05 INFO - PROCESS | 1673 | colorDepth:24 22:26:05 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 22:26:05 INFO - PROCESS | 1673 | __metrics 22:26:05 INFO - PROCESS | 1673 | 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" 22:26:05 INFO - PROCESS | 1673 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:26:06 INFO - TEST-INFO | 1673: exit 0 22:26:06 INFO - Browser initialized. 22:26:06 INFO - Running cycle 1/1 for kraken test... 22:26:06 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmp3PCSIz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:26:18 INFO - PROCESS | 1674 | RSS: Main: 245186560 22:26:18 INFO - PROCESS | 1674 | 22:26:20 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 22:26:21 INFO - PROCESS | 1674 | RSS: Main: 294334464 22:26:21 INFO - PROCESS | 1674 | 22:26:24 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 22:26:24 INFO - PROCESS | 1674 | RSS: Main: 304775168 22:26:24 INFO - PROCESS | 1674 | 22:26:28 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 22:26:29 INFO - PROCESS | 1674 | RSS: Main: 393863168 22:26:29 INFO - PROCESS | 1674 | 22:26:31 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 22:26:31 INFO - PROCESS | 1674 | RSS: Main: 294686720 22:26:31 INFO - PROCESS | 1674 | 22:26:33 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:26:34 INFO - PROCESS | 1674 | RSS: Main: 298610688 22:26:34 INFO - PROCESS | 1674 | 22:26:37 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 22:26:38 INFO - PROCESS | 1674 | RSS: Main: 336834560 22:26:38 INFO - PROCESS | 1674 | 22:26:41 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 22:26:41 INFO - PROCESS | 1674 | RSS: Main: 337977344 22:26:41 INFO - PROCESS | 1674 | 22:26:44 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 22:26:45 INFO - PROCESS | 1674 | RSS: Main: 335716352 22:26:45 INFO - PROCESS | 1674 | 22:26:46 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:26:47 INFO - PROCESS | 1674 | RSS: Main: 312082432 22:26:47 INFO - PROCESS | 1674 | 22:26:49 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:26:49 INFO - PROCESS | 1674 | RSS: Main: 319598592 22:26:49 INFO - PROCESS | 1674 | 22:26:51 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:26:52 INFO - PROCESS | 1674 | RSS: Main: 316833792 22:26:52 INFO - PROCESS | 1674 | 22:26:55 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:26:56 INFO - PROCESS | 1674 | RSS: Main: 325619712 22:26:56 INFO - PROCESS | 1674 | 22:26:59 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:26:59 INFO - PROCESS | 1674 | RSS: Main: 327761920 22:26:59 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 22:27:01 INFO - PROCESS | 1674 | RSS: Main: 370884608 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | __start_tp_report 22:27:01 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 22:27:01 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 22:27:01 INFO - PROCESS | 1674 | |i|pagename|runs| 22:27:01 INFO - PROCESS | 1674 | |0;ai-astar;102;113;114;112;113;113;112;113;115;160 22:27:01 INFO - PROCESS | 1674 | |1;audio-beat-detection;138;110;108;187;138;137;125;105;163;107 22:27:01 INFO - PROCESS | 1674 | |2;audio-dft;187;184;184;181;181;183;180;184;182;184 22:27:01 INFO - PROCESS | 1674 | |3;audio-fft;78;75;60;105;85;81;83;66;83;57 22:27:01 INFO - PROCESS | 1674 | |4;audio-oscillator;85;88;93;74;94;100;132;73;68;102 22:27:01 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;89;84;87;90;136;114;94;88;89;88 22:27:01 INFO - PROCESS | 1674 | |6;imaging-darkroom;108;151;94;94;94;93;93;94;94;92 22:27:01 INFO - PROCESS | 1674 | |7;imaging-desaturate;86;84;84;84;87;84;84;86;84;84 22:27:01 INFO - PROCESS | 1674 | |8;json-parse-financial;51;51;50;51;52;53;52;52;51;52 22:27:01 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;66;71;71;85;74;73;70;76;71;73 22:27:01 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;83;85;81;84;82;82;98;101;102;101 22:27:01 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;139;135;134;131;133;134;135;141;243;138 22:27:01 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;164;166;227;208;164;166;165;163;163;163 22:27:01 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;67;68;67;67;69;67;66;66;66;66 22:27:01 INFO - PROCESS | 1674 | __end_tp_report 22:27:01 INFO - PROCESS | 1674 | __start_cc_report 22:27:01 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,2026 22:27:01 INFO - PROCESS | 1674 | __end_cc_report 22:27:01 INFO - PROCESS | 1674 | __startTimestamp1468992421706__endTimestamp 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | ------- Summary: start ------- 22:27:01 INFO - PROCESS | 1674 | Number of tests: 14 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:116.70 Median:113.00 stddev:15.64 (13.8%) stddev-sans-first:15.65 22:27:01 INFO - PROCESS | 1674 | Values: 102.0 113.0 114.0 112.0 113.0 113.0 112.0 113.0 115.0 160.0 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:131.80 Median:137.50 stddev:26.97 (19.6%) stddev-sans-first:28.51 22:27:01 INFO - PROCESS | 1674 | Values: 138.0 110.0 108.0 187.0 138.0 137.0 125.0 105.0 163.0 107.0 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:183.00 Median:184.00 stddev:2.05 (1.1%) stddev-sans-first:1.59 22:27:01 INFO - PROCESS | 1674 | Values: 187.0 184.0 184.0 181.0 181.0 183.0 180.0 184.0 182.0 184.0 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:77.30 Median:79.50 stddev:13.94 (17.5%) stddev-sans-first:14.79 22:27:01 INFO - PROCESS | 1674 | Values: 78.0 75.0 60.0 105.0 85.0 81.0 83.0 66.0 83.0 57.0 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:79.50 stddev:18.51 (23.3%) stddev-sans-first:19.51 22:27:01 INFO - PROCESS | 1674 | Values: 85.0 88.0 93.0 74.0 94.0 100.0 132.0 73.0 68.0 102.0 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:95.90 Median:88.50 stddev:16.38 (18.5%) stddev-sans-first:17.18 22:27:01 INFO - PROCESS | 1674 | Values: 89.0 84.0 87.0 90.0 136.0 114.0 94.0 88.0 89.0 88.0 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:100.70 Median:94.00 stddev:18.26 (19.4%) stddev-sans-first:19.18 22:27:01 INFO - PROCESS | 1674 | Values: 108.0 151.0 94.0 94.0 94.0 93.0 93.0 94.0 94.0 92.0 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:84.00 stddev:1.16 (1.4%) stddev-sans-first:1.13 22:27:01 INFO - PROCESS | 1674 | Values: 86.0 84.0 84.0 84.0 87.0 84.0 84.0 86.0 84.0 84.0 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:52.00 stddev:0.85 (1.6%) stddev-sans-first:0.88 22:27:01 INFO - PROCESS | 1674 | Values: 51.0 51.0 50.0 51.0 52.0 53.0 52.0 52.0 51.0 52.0 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:73.00 stddev:4.99 (6.8%) stddev-sans-first:4.60 22:27:01 INFO - PROCESS | 1674 | Values: 66.0 71.0 71.0 85.0 74.0 73.0 70.0 76.0 71.0 73.0 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:82.50 stddev:9.24 (11.2%) stddev-sans-first:9.46 22:27:01 INFO - PROCESS | 1674 | Values: 83.0 85.0 81.0 84.0 82.0 82.0 98.0 101.0 102.0 101.0 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:146.30 Median:136.50 stddev:34.11 (25.0%) stddev-sans-first:36.07 22:27:01 INFO - PROCESS | 1674 | Values: 139.0 135.0 134.0 131.0 133.0 134.0 135.0 141.0 243.0 138.0 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.90 Median:165.50 stddev:22.92 (13.9%) stddev-sans-first:23.97 22:27:01 INFO - PROCESS | 1674 | Values: 164.0 166.0 227.0 208.0 164.0 166.0 165.0 163.0 163.0 163.0 22:27:01 INFO - PROCESS | 1674 | 22:27:01 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 22:27:01 INFO - PROCESS | 1674 | Values: 67.0 68.0 67.0 67.0 69.0 67.0 66.0 66.0 66.0 66.0 22:27:01 INFO - PROCESS | 1674 | -------- Summary: end -------- 22:27:01 INFO - PROCESS | 1674 | 22:27:02 INFO - TEST-INFO | 1674: exit 0 22:27:02 INFO - TEST-OK | kraken | took 59509ms 22:27:02 INFO - SUITE-END | took 1176s 22:27:02 INFO - Completed test suite (00:19:36) 22:27:02 INFO - javascript benchmark 22:27:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3316.7678262521626, 3333.8666453341884, 3349.832508374581, 3360.2103505676178, 3421.6065152507454, 5534.529564986971, 5625.859353516164, 5687.829365119042, 5712.314522257325, 5771.422857714237, 5699.544036477082, 5702.942970570293, 5802.39074897136, 5813.73838177282, 5840.8247752567495, 5133.153029750087, 5172.250023746555, 5225.477452254782, 5234.790608375661, 5334.119870221428, 5289.047971365153, 5320.015797077553, 5337.066013447631, 5338.27933229015, 5367.382750983628, 5143.562797162227, 5185.585153187736, 5205.635605507616, 5232.607554433433, 5233.633645644806, 5230.869228269324, 5234.790608375661, 5242.475752424765, 5243.344581927259, 5265.7893684252585, 5151.845444636667, 5153.0, 5187.429382767892, 5222.268882356473, 5300.072487314743, 5677.914831277534, 5776.480116789509, 5789.102689083199, 5795.971020144914, 5828.446297601721, 6197.87604247917, 6274.717637706314, 6277.497800175975, 6282.371762823726, 6298.118263443122, 5120.283160357553, 6144.832481828438, 6157.0, 6212.0371342441995, 6254.280757712831, 5751.367349591542, 5761.827145185651, 5778.5377169826315, 5818.941810581882, 5824.3593204748295, 6225.035119556476, 6259.7496100156395, 6281.183446151971, 6282.089097080898, 6286.025666021774, 6130.846728831726, 6153.138560601519, 6156.5998210116195, 6221.222347206599, 6226.906596401058, 6293.402126798039, 6304.401081897213, 6305.5901366411035, 6309.558330916883, 6347.079673447417, 6075.5747097703625, 6266.185395898504, 6266.843328916722, 6268.498520118471, 6284.528660350492, 6109.572329936862, 6165.907511387333, 6206.906896396557, 6217.098520714471, 6223.471004964629, 4910.901781964412, 4957.529034741694, 4965.379327584052, 4982.377202849644, 4997.825076122318, 4417.624501917308, 4424.6902716810155, 4444.6666500012625, 4450.888727781767, 4467.173572889026, 5566.888662226733, 5708.429157084258, 5828.242328497268, 5848.0, 5883.764649413975, 6187.752489900444, 6247.062940558862, 6263.592866463665, 6292.622442653455, 6328.493720502349, 5764.942350576524, 5786.103154011135, 5791.189233507313, 5812.796552120655, 5860.824175274749, 5313.415524292324, 5372.946270537323, 5392.622516423813, 5428.891422171534, 5431.809886654027, 6241.937580624136, 6287.245530536365, 6298.842528936813, 6318.178636777281, 6320.241571011508, 6228.03465462854, 6276.623402595859, 6287.905681414691, 6299.464545513682, 6327.873442531216, 6217.564770466024, 6237.96881015592, 6255.687215639291, 6264.436200741955, 6325.841853953688, 6227.595206311576, 6240.344763799917, 6252.656103914328, 6253.3121356651645, 6259.904516709648, 6244.438000579969, 6270.811875643738, 6277.654728989949, 6287.402696743802, 6297.685115744286, 5961.0462326029365, 5980.491658209101, 6079.966405710951, 6133.6319820810895, 6150.846228844314, 6093.6417855416485, 6133.3866613338305, 6146.139540464428, 6156.907646385218, 6175.382461753788, 6042.728077236447, 6061.272647282354, 6066.878662426815, 6088.543359247986, 6097.2683278006925, 5807.216025836627, 5920.141579471038, 5948.672822994604, 6146.723397447215, 6176.382361763967, 5938.287405511366, 5941.91087133702, 5956.344802071855, 5963.92649323126, 5966.343702192842], "value": 5721.719573272984, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.5335737763879, 515.5901058658367, 523.7695414017833, 535.2052202479317, 543.5705792423985, 30366.84816575917, 31015.379692406168, 41037.0, 41106.76679699606, 41553.16893662129, 42717.572824271745, 42998.35502467465, 43205.91985200372, 43230.13539729211, 43333.0, 8615.784605384853, 9566.856497152548, 9733.707988760312, 9741.90258097417, 9765.365251258645, 8595.484270943733, 11906.880931190664, 11997.880021199764, 12009.879901200964, 12360.381980900964, 11973.46119424628, 11992.460339284753, 12016.93991530041, 12042.494688163979, 12050.819237711441, 8726.127387261255, 8739.169778870997, 8741.912580874174, 11633.36016519091, 11783.351915644636, 11571.594994175164, 11649.883501164964, 11657.242279251821, 11660.24208426458, 11687.590934317259, 39764.403533947014, 40102.799486002674, 40159.79520614386, 40273.99315017118, 40282.597174028175, 3522.5949015862993, 3529.6117427083, 3565.5008298838184, 3578.624244454321, 3600.304503365953, 31608.0, 32270.0, 32317.83841080803, 32324.6767532324, 32473.350532989443, 3213.8875139370243, 3289.73682105431, 3328.4341661917533, 3387.8814241501427, 3399.847006884696, 512402.9999999963, 513012.0, 515166.0, 516341.0, 517320.0, 57321.426785732445, 58390.41609583935, 58484.70757646184, 58542.41457585455, 58583.0, 60150.39849601448, 60175.69912150411, 60186.699066504385, 60240.0, 60254.69872650696, 16283.918580407259, 16548.917255413646, 16557.337706491606, 16560.00639961606, 16585.419510317322, 11770.76458470843, 11978.101642376681, 11990.0, 12152.696182595506, 12925.418356173996, 13650.726985460435, 13735.519256826163, 17068.914655426644, 18508.81491185098, 18604.883706977624, 5575.4424557543925, 7166.462515311268, 7167.569945803269, 7214.71141154348, 7323.608514382357, 935.653808090997, 1006.8892421833592, 1037.7509397744486, 1064.2864987370328, 1085.1156307609274, 29943.550846737315, 30004.54993175104, 30045.69954300429, 30749.38501229963, 30771.076867694006, 1054.9630762923261, 1070.8541860209657, 1112.1547623805805, 1140.201858698914, 1256.9937150314372, 14022.948278879125, 15548.850317171018, 15575.532734017997, 15641.530754077396, 15809.288582013836, 177699.0, 178230.0, 178980.0, 178981.0, 179751.0, 60011.69994150088, 61810.0, 62077.0, 62100.68949655223, 62164.06753898695, 1244.8257243985668, 1275.3113318807739, 1347.6091933339417, 1395.5813256023355, 1435.224978511613, 29762.851185743933, 30634.540481893237, 31336.21659458532, 31610.36779264448, 32333.0, 56770.43229567652, 57054.57363565944, 57674.0, 57691.711541442026, 57782.0, 603679.0, 613116.0, 614732.0, 615225.0, 615587.0, 23375.76624233736, 27215.86392068027, 27320.316992075357, 27359.179224623294, 28814.0, 49524.75237623789, 49624.51126466212, 50055.49944500509, 51146.48853511566, 51230.74384628202, 39296.0, 40012.59987400089, 40384.79807600943, 40533.79733101316, 40803.59196408117, 38784.22431551297, 38903.61096389113, 39352.60647393604, 40027.59972400355, 40177.39733903935, 37732.0, 38261.42607860829, 38472.230555388174, 38486.80756596199, 38533.807330963165, 34649.82675086609, 35504.3832263541, 35627.64372356243, 36035.45946810852, 36127.096822579646, 26032.47935041251, 26166.24448541339, 26176.476470470865, 26325.736742633093, 26687.866560667073], "value": 19846.398769996726, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.38705410841635, 350.7071595217994, 354.79421935277543, 360.4035321542847, 410.1755471502281, 15602.531924042269, 17336.0, 20259.088341024653, 20933.05801238944, 21000.89499552504, 20002.0, 20438.08028638711, 20778.584428311424, 20843.583128337425, 20922.26772062978, 5527.977324195037, 5725.112607545837, 6002.0, 6007.128966299884, 6008.068749343837, 5802.477777000069, 5881.85295367615, 5905.025670764318, 5925.0, 5933.3473317934995, 5565.0817615093465, 5773.191753154561, 5786.276715410574, 5873.2658417697785, 5876.941230587682, 5763.8760441713885, 5788.160716696098, 5806.361300256968, 5806.390329015477, 5822.475977162055, 5076.390833100015, 5230.8692282692855, 5565.693886836222, 5566.165075238747, 5587.441255874421, 12940.676483087904, 13004.674883127904, 13102.08285420021, 13160.210387376692, 13188.274644894527, 2127.9574408511744, 2129.8935053247274, 2159.7948194921455, 2209.3924170852983, 2332.0322066342446, 9891.653792117313, 9892.604295828158, 9958.850617240747, 10371.481425928749, 10395.480225988671, 1863.1522657190947, 1951.7072439134101, 1952.9746882886457, 1961.4017724593994, 1982.9589465530566, 118706.0, 120681.0, 128191.35904320418, 128417.35791321077, 129654.0, 9326.72019839406, 11314.03830674402, 11495.770084598262, 11500.102483090364, 11704.531818727155, 12318.199317044542, 13449.453312869146, 14390.0, 14408.711825763427, 14451.0, 6463.2244130703675, 6507.381798729113, 6870.931290687029, 7941.563214023284, 8506.106858779864, 6404.935950640434, 6810.387065164159, 8315.916840831514, 8319.750407487785, 8443.366747493963, 3902.297586434412, 4532.09945634794, 4536.3422303765765, 4541.227991241497, 4590.67865249436, 1886.4246404846654, 1934.7581552305962, 1940.7865134835156, 1942.931997380085, 1944.3680803738841, 837.7570504553611, 854.3336197765752, 932.3333816321363, 943.4056544376998, 945.4469135555639, 20355.491112722302, 20794.584108317747, 20886.89556552238, 20921.025005248954, 20927.63970341923, 828.5898480252193, 853.3770347646129, 900.5677274907949, 904.791897863482, 929.0384452092052, 10771.138308935266, 11012.449377531091, 11042.944785276022, 11047.0, 11333.43332833355, 590480.0, 601246.0, 624387.0, 628615.0, 634832.0, 93688.0, 121630.0, 143158.0, 147889.0, 151761.0, 1000.9149222316184, 1013.7313611892854, 1023.518946095334, 1060.7825395793843, 1063.3035361838008, 18249.270029198684, 18426.447206583824, 18428.631427371645, 18438.26246950107, 18573.0, 54090.0, 58744.0, 59121.40878591159, 59149.40850591611, 59236.11145832902, 87577.37267881974, 88044.0, 88168.55915720381, 88315.55842220748, 90819.18361632862, 7059.611721355374, 7894.4868583544185, 7993.240642138988, 8088.9595552021865, 8320.958395208227, 11724.531018759495, 12617.242965422103, 12686.429110689856, 12930.482780688668, 13032.609021729364, 11131.944340278247, 11206.663800086011, 11216.0, 11228.438578071227, 11286.20996530235, 10705.19711021661, 11102.944485277521, 11118.444077795915, 11166.888331116585, 11281.830772538582, 9629.614815407585, 10704.732381690521, 11203.159763017644, 11407.087433005505, 11604.6518604442, 6841.726330946706, 7953.443258972048, 8029.3175080118845, 8109.1485394035135, 8231.670733170606, 10073.640058591996, 10482.37105773656, 11045.005949464587, 11300.49147788335, 11303.095752339957], "value": 9307.776086826698, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1504.8570385813348, 1542.6066353079966, 1728.7234042553187, 1879.962400751985, 1904.2763749775083, 7956.880646790303, 8215.26062654361, 8567.528785916773, 8889.28885689145, 8908.64365425383, 6810.387065164135, 8508.659653613864, 8740.606672699729, 8746.387752857303, 8857.778555536099, 2355.140373763577, 2381.7975472084895, 2384.523095380922, 2385.1294277588727, 2386.200622791367, 2100.7584127825266, 2177.314146043999, 2316.108298305148, 2360.929172124839, 2386.96419553706, 2332.6734257204007, 2357.917472888449, 2361.468669549346, 2362.917297894574, 2366.2428023032635, 2321.3384185507134, 2366.479374537599, 2369.609014512603, 2370.8221883358647, 2372.478054827935, 2328.918487852917, 2345.2260753951155, 2359.2922123362923, 2360.3155085025323, 2361.0909799727056, 8873.645054197825, 9038.367314288002, 9040.276777857756, 9040.683576074805, 9046.004939456654, 5247.711375874325, 5400.405955344909, 5402.8919421611745, 5404.567634589223, 5407.486288802596, 3529.770564913272, 3609.657082577151, 3654.4701018352457, 3654.853805847763, 3654.9817250913843, 1035.414990530348, 1072.201210098474, 1091.8089334366455, 1096.9725756856062, 1097.6706987903597, 11885.22746021506, 11894.52421903123, 11896.286222826659, 11905.642830715005, 11909.880901190963, 4574.222382194968, 4676.158291507561, 4739.715617062987, 4740.5733483986605, 4764.404449443819, 9732.464714440632, 9877.950610246902, 9990.950045249727, 10000.79998400028, 10002.749931251632, 5184.611154163453, 5258.710770907637, 5328.813491527778, 5343.893122137535, 5345.358556973188, 4390.582894625006, 4910.0, 4911.287863259808, 4913.901721965541, 4923.852284431473, 3674.191677830873, 3886.689064874803, 3960.207958408272, 4017.5178978522763, 4044.757314561077, 1284.556827894375, 1289.4842063174806, 1292.0826213388411, 1298.2016060123144, 1305.6278960496213, 766.8542976834495, 883.3992884838243, 917.2114376964945, 926.1710768861831, 932.2728271947891, 5782.0, 5806.564507661942, 5906.734196961061, 5907.734151963172, 6028.276606807123, 918.912703293186, 933.4679232837218, 955.1165172215811, 956.6986399284203, 975.0541974285075, 3445.793252404864, 3983.2232714620372, 4055.452513910643, 4060.7766572838186, 4061.614146656122, 10131.949340253252, 10828.404437755842, 10846.457677116112, 10864.130869530418, 11143.387113708663, 9166.54167291646, 10321.380717156995, 10528.105111065644, 10629.681109566725, 10813.729656758487, 8089.595520223966, 8356.707515236938, 8378.706745263886, 8398.160183981676, 8399.664013439517, 4290.95709042918, 5334.866628334323, 5823.213866128019, 5859.150423188528, 6198.81403557894, 9802.607895684092, 9906.752331191778, 10147.949260253652, 10176.287659863738, 10279.126274266771, 10019.949900250453, 10379.221558383, 10384.584616615251, 10405.231110711045, 10468.214883883587, 7063.71745130189, 7447.851042979219, 7690.807729806799, 7760.262775036363, 7784.455088143775, 7949.682012719427, 8438.915610843813, 8584.969803623606, 8593.0, 8596.699115531177, 7708.421868359784, 7835.451518393657, 7875.369970402468, 7886.408519361185, 8072.354211663171, 7730.149683534919, 7836.725714600189, 7851.921480785348, 7874.291313781787, 8053.758387248392, 6933.514653974174, 6946.0622815919205, 7020.227774944854, 7052.33002864727, 7070.292970702889, 5099.898002040012, 5234.528892399702, 5257.158854583248, 5313.0, 5333.679979201261, 4114.876553703393, 5267.051930652366, 5278.709670968134, 5319.973400133129, 5356.303680521507], "value": 4623.932692860922, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.080228912119, 310.91449851290895, 319.61007570763667, 320.09572956398165, 323.40170684234187, 4374.803133858976, 4616.930746038808, 4915.606751459874, 5367.355917289921, 5376.892462150755, 4401.823927042915, 5335.866603334918, 5393.568514518839, 5403.4603736244, 5404.9189262161, 3122.9219269518217, 3571.5357003589497, 4032.8185231664647, 4109.541112904921, 4115.588441155883, 4130.752154870703, 4138.1103062841485, 4139.337705967046, 4158.209940111369, 4171.728837625545, 3338.8814747059764, 3750.8687195948146, 3986.368996462321, 3988.5811989741096, 4019.9900049975013, 3971.6822654187595, 3985.940210896839, 3986.262541429816, 4002.079521709994, 4007.098402859348, 3109.709470569711, 3304.8512816923294, 3487.3548393547276, 3505.371940042044, 3515.3145136698354, 4906.877328066791, 5203.843884683465, 5211.843644690627, 5231.402399432571, 5237.3453318335205, 4039.5556488786506, 4163.877092180865, 4237.131388065439, 4241.533431322552, 4631.745254011028, 5467.715086954562, 5593.937151941118, 5612.7754889804355, 5640.661560306354, 5680.0, 3263.526788615661, 3730.477733117366, 3772.6986538211668, 3788.583255841855, 3804.714646401531, 4476.09054114828, 4700.506446823103, 5620.932022915633, 5831.679257640828, 5899.675517846516, 3712.814359282025, 3865.724310977371, 3883.8834834954996, 3890.4164375343926, 3893.4549163117185, 3299.307145499472, 3927.3716205407, 3939.8030098495533, 3942.7974467787026, 3952.6047395260243, 3808.5810560838286, 4342.913141737147, 4371.797755617231, 4585.862424127282, 4613.169629466728, 4672.616965347629, 5603.411641777636, 5612.382637909826, 5637.408072152447, 5660.8867822643315, 1135.0408904475705, 1157.976840463186, 1317.873218398275, 1352.114365090867, 1354.2416246901766, 737.3474475089585, 790.9519886150758, 806.4798205157758, 845.7251393297203, 914.07221670005, 4330.891727706769, 4892.804287828518, 4955.90088198234, 5310.628256022119, 5362.597805164628, 4880.633952453581, 5441.809536666197, 5507.9877311646205, 5750.4473792075905, 5824.514748739044, 4497.2129877271345, 4977.502249774993, 5078.4413714491375, 5269.841904742864, 5307.3896501902, 2644.2067379785985, 2774.9722502775116, 2996.7752418568693, 3003.8347890865775, 3004.6694863565426, 5728.541716662657, 5779.156860687595, 5925.466707996301, 5930.436608522184, 5956.936603886748, 5477.20580515831, 5602.02256397962, 5893.233879595625, 5919.852003699856, 5966.850828729316, 5446.891062178814, 5530.428350816889, 5901.881962360814, 5905.4685078343155, 5909.87712334655, 4968.527989840959, 5443.237946687546, 5496.203050557727, 5662.065759149694, 5865.912011319749, 4820.469748327611, 5111.080005599028, 5502.569331973796, 5810.564207684356, 5820.708964551671, 4704.248014911315, 5532.751026203911, 5814.796482123105, 5997.6701281428195, 6039.335673075871, 3928.941065883957, 3932.1545867638597, 3947.7039222059034, 3949.269385163754, 3949.348357520977, 5461.672299662033, 5923.763049478145, 5950.185193516019, 5958.702064896651, 5980.730867110903, 5182.222666600041, 5399.514043736082, 5455.5908306876345, 5698.458646428583, 5733.8566535836935, 5136.922946155736, 5293.682379057115, 5648.864879991644, 5684.759686978383, 5733.627314224562, 5095.541401273903, 5286.629935904603, 5594.41258667834, 5603.691796951207, 5608.943910561005, 5183.740812959412, 5656.490915817596, 5684.346300175533, 5712.85742851423, 5737.397573254915, 4310.986918074187, 5592.468715472024, 5597.608167428241, 5620.690862002465, 5723.370429252695], "value": 4062.42915262725, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.0711414289208, 748.7042618165825, 763.6945221911232, 848.6138806842887, 849.0175440346792, 17752.733708994376, 18224.0, 18248.90875545622, 18278.725819112726, 18290.993995330253, 14570.562883113496, 16929.899556528846, 17178.05520696361, 17299.481015569523, 17384.52231560319, 724.1744411371042, 745.7949064007387, 899.1817446124029, 907.4147175072075, 908.5865931001412, 846.7586737779734, 846.9153084691543, 885.1856292211181, 916.7891384981458, 921.0651189043126, 926.874871892296, 942.241495596045, 942.29328003997, 943.0, 946.1673727120125, 742.7697413801739, 939.5631031570318, 939.8073394954018, 946.4841661294543, 969.2343048991288, 634.4511997122502, 634.7524465458475, 639.9328070552572, 705.4039336760429, 705.5378726933867, 15834.920825395915, 15871.444499442465, 15880.0, 15895.761563576556, 15909.204539773082, 12238.571649992296, 14749.262536873113, 14907.105573665614, 14915.0, 14917.925410372878, 11655.708607284801, 12016.459259333351, 12198.39008049594, 12322.445489952974, 12469.189502682388, 3831.2337532493334, 3945.881623551298, 3964.365701487748, 3999.6600288975474, 4087.8773636790943, 86985.13014869897, 111620.0, 112174.87825121645, 112657.0, 112930.0, 10076.697699068893, 10254.205514035015, 10496.370217786805, 10522.526486308134, 10540.051395374454, 23541.0, 23657.645135322982, 23668.763312367002, 23673.28980130599, 23897.761022389903, 8169.387295952827, 8193.180681931759, 8228.950065166991, 8242.711505097293, 8490.533020683799, 4531.048479819209, 4576.839810606612, 4605.332226827091, 4697.177993851063, 4734.455537613218, 3570.6786389225094, 3601.099725068733, 3681.502997095358, 3682.2451397463456, 3683.2633473305445, 675.4832553096885, 676.3946268090032, 678.8845896197658, 685.0272612889709, 688.0333131949619, 595.8272101090722, 612.301975747648, 645.2579533536377, 681.7273090763734, 702.880510313248, 8970.775730606656, 9133.360664753403, 9314.906850931404, 9645.951770241245, 9774.071463210983, 552.8203333916493, 644.948404127678, 650.8698260347854, 680.860423613168, 701.2391555162603, 4358.716683415568, 4567.634589232854, 4625.309975601111, 4773.689710168828, 4778.283257511401, 129435.0, 149986.25006875113, 152121.0, 152373.0, 158676.0, 139390.30304848411, 139487.30256348653, 139843.0, 141817.0, 145508.0, 73758.6312068447, 73881.6305918467, 99370.0, 106178.0, 122022.0, 9469.337146399686, 9818.500762476226, 9818.752248111621, 9859.408435493893, 10086.94956525242, 51695.741521292155, 62757.0, 62781.0, 63489.0, 65346.67326663336, 83120.58439707763, 83905.12664113453, 85778.57110714406, 88379.72012938806, 90108.0, 13059.477620895057, 13123.0, 13400.731985360433, 13473.797893031417, 13582.728345433234, 14862.628434289229, 15060.0, 15068.472603458826, 15324.770128448305, 15488.612784680474, 13905.37425815861, 13934.930325348309, 13950.23273719955, 14002.57992260234, 14470.0, 11539.973037911192, 13855.722885542435, 13885.305734713022, 14043.36804843764, 14117.0, 13356.131851429625, 13548.0, 13572.592822215349, 13696.31518424055, 14131.293435328398, 11088.944555277172, 11206.327620342805, 11601.825972610586, 11609.767804644029, 12121.27272363661, 10504.947475262576, 10587.20595955291, 11613.0, 11624.418779061183, 11637.0], "value": 8017.3447756536025, "unit": "score"}], "name": "dromaeo_css", "value": 7361.866281855366, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 114.0, 112.0, 113.0, 113.0, 112.0, 113.0, 115.0, 160.0], "value": 116.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 110.0, 108.0, 187.0, 138.0, 137.0, 125.0, 105.0, 163.0, 107.0], "value": 131.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 184.0, 181.0, 181.0, 183.0, 180.0, 184.0, 182.0, 184.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 60.0, 105.0, 85.0, 81.0, 83.0, 66.0, 83.0, 57.0], "value": 77.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 93.0, 74.0, 94.0, 100.0, 132.0, 73.0, 68.0, 102.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 87.0, 90.0, 136.0, 114.0, 94.0, 88.0, 89.0, 88.0], "value": 95.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 151.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 92.0], "value": 100.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 84.0, 87.0, 84.0, 84.0, 86.0, 84.0, 84.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 50.0, 51.0, 52.0, 53.0, 52.0, 52.0, 51.0, 52.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 71.0, 85.0, 74.0, 73.0, 70.0, 76.0, 71.0, 73.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 81.0, 84.0, 82.0, 82.0, 98.0, 101.0, 102.0, 101.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 135.0, 134.0, 131.0, 133.0, 134.0, 135.0, 141.0, 243.0, 138.0], "value": 146.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 227.0, 208.0, 164.0, 166.0, 165.0, 163.0, 163.0, 163.0], "value": 174.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 69.0, 67.0, 66.0, 66.0, 66.0, 66.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1483.5000000000002, "alertThreshold": 2.0}]} 22:27:02 INFO - Return code: 0 22:27:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:27:02 INFO - # TBPL SUCCESS # 22:27:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:27:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:27:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:27:03 INFO - 2016-07-19 22:27:03.009 screenresolution[1678:11385] starting screenresolution argv=screenresolution get 22:27:03 INFO - 2016-07-19 22:27:03.011 screenresolution[1678:11385] Display 0: 1600x1200x32@60 22:27:03 INFO - 2016-07-19 22:27:03.017 screenresolution[1679:11388] starting screenresolution argv=screenresolution list 22:27:03 INFO - Available Modes on Display 0 22:27:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:27:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:27:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:27:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:27:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:27:03 INFO - Intel Iris: 22:27:03 INFO - Chipset Model: Intel Iris 22:27:03 INFO - Type: GPU 22:27:03 INFO - Bus: Built-In 22:27:03 INFO - VRAM (Dynamic, Max): 1536 MB 22:27:03 INFO - Vendor: Intel (0x8086) 22:27:03 INFO - Device ID: 0x0a2e 22:27:03 INFO - Revision ID: 0x0009 22:27:03 INFO - Displays: 22:27:03 INFO - Display: 22:27:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:27:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:27:03 INFO - Display Serial Number: 1600x1200 60 22:27:03 INFO - Main Display: Yes 22:27:03 INFO - Mirror: Off 22:27:03 INFO - Online: Yes 22:27:03 INFO - Rotation: Supported 22:27:03 INFO - Return code: 0 22:27:03 INFO - Running post-action listener: _resource_record_post_action 22:27:03 INFO - [mozharness: 2016-07-20 05:27:03.271654Z] Finished run-tests step (success) 22:27:03 INFO - Running post-run listener: _resource_record_post_run 22:27:03 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 31390720; Write bytes: 704813056; Read time: 199; Write time: 1950 22:27:03 INFO - TinderboxPrint: CPU usage
53.3% 22:27:03 INFO - TinderboxPrint: I/O read bytes / time
31,390,720 / 199 22:27:03 INFO - TinderboxPrint: I/O write bytes / time
704,813,056 / 1,950 22:27:03 INFO - TinderboxPrint: CPU idle
2,262.9 (46.7%) 22:27:03 INFO - TinderboxPrint: CPU system
966.8 (20.0%) 22:27:03 INFO - TinderboxPrint: CPU user
1,616.0 (33.3%) 22:27:03 INFO - TinderboxPrint: Swap in / out
435,523,584 / 0 22:27:03 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 397292032; Write bytes: 391865856; Read time: 29369; Write time: 822 22:27:03 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 29633536; Write bytes: 293212672; Read time: 185; Write time: 1079 22:27:03 INFO - Running post-run listener: _upload_blobber_files 22:27:03 INFO - Blob upload gear active. 22:27:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:27:03 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 22:27:03 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'] 22:27:03 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 22:27:04 INFO - (blobuploader) - INFO - Open directory for files ... 22:27:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:27:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:27:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:27:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:27:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:27:05 INFO - (blobuploader) - INFO - Done attempting. 22:27:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:27:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:27:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:27:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:27:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:27:06 INFO - (blobuploader) - INFO - Done attempting. 22:27:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:27:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:27:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:27:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:27:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:27:09 INFO - (blobuploader) - INFO - Done attempting. 22:27:09 INFO - (blobuploader) - INFO - Iteration through files over. 22:27:09 INFO - Return code: 0 22:27:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:27:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:27:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93962ebf8777fbf8bbcb2daba29d9fdaf8b248910f9d82c04c44531dd68870e5f0d6b171c5afefe4aa993adda05bcad09eedc374d05eb73d3c2b48f9b546e683", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/183a122139e6391316f8570a4dd31a9124b5f342c2e00ee511f925875bcd12da1e22c8ba4d5913643e7dfaffab873a626645ab28039a2af147d92ac3c9905cbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d3cb875954926354561eea96d966a30b12dc8a6ce9460e4b968a9dc719fb37ec45ca55a5f878062c2dbbcb4a075ce8426ce3a34e207664930e79b94efe5a23d"} 22:27:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:27:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:27:09 INFO - Contents: 22:27:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93962ebf8777fbf8bbcb2daba29d9fdaf8b248910f9d82c04c44531dd68870e5f0d6b171c5afefe4aa993adda05bcad09eedc374d05eb73d3c2b48f9b546e683", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/183a122139e6391316f8570a4dd31a9124b5f342c2e00ee511f925875bcd12da1e22c8ba4d5913643e7dfaffab873a626645ab28039a2af147d92ac3c9905cbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d3cb875954926354561eea96d966a30b12dc8a6ce9460e4b968a9dc719fb37ec45ca55a5f878062c2dbbcb4a075ce8426ce3a34e207664930e79b94efe5a23d"} 22:27:09 INFO - Running post-run listener: copy_logs_to_upload_dir 22:27:09 INFO - Copying logs to upload dir... 22:27:09 INFO - mkdir: /builds/slave/test/build/upload/logs 22:27:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.786847 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-07-19 22:27:09.808045) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 22:27:09.811264) ========= 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.pM9JNbHLF2/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.V8fputjVMm/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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/93962ebf8777fbf8bbcb2daba29d9fdaf8b248910f9d82c04c44531dd68870e5f0d6b171c5afefe4aa993adda05bcad09eedc374d05eb73d3c2b48f9b546e683", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/183a122139e6391316f8570a4dd31a9124b5f342c2e00ee511f925875bcd12da1e22c8ba4d5913643e7dfaffab873a626645ab28039a2af147d92ac3c9905cbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d3cb875954926354561eea96d966a30b12dc8a6ce9460e4b968a9dc719fb37ec45ca55a5f878062c2dbbcb4a075ce8426ce3a34e207664930e79b94efe5a23d"} build_url:https://queue.taskcluster.net/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011221 build_url: 'https://queue.taskcluster.net/v1/task/eASJS7L3QCumvkQbClgxYg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93962ebf8777fbf8bbcb2daba29d9fdaf8b248910f9d82c04c44531dd68870e5f0d6b171c5afefe4aa993adda05bcad09eedc374d05eb73d3c2b48f9b546e683", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/183a122139e6391316f8570a4dd31a9124b5f342c2e00ee511f925875bcd12da1e22c8ba4d5913643e7dfaffab873a626645ab28039a2af147d92ac3c9905cbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d3cb875954926354561eea96d966a30b12dc8a6ce9460e4b968a9dc719fb37ec45ca55a5f878062c2dbbcb4a075ce8426ce3a34e207664930e79b94efe5a23d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 22:27:09.843950) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:27:09.844284) ========= 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.pM9JNbHLF2/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.V8fputjVMm/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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.005012 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:27:09.893157) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 22:27:09.893465) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 22:27:09.893805) ========= ========= Total master_lag: 0.17 =========