builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0346 starttime: 1468969746.41 results: success (0) buildid: 20160719151524 builduid: f1c744dfc63c4cdf8e8600ad90b987f8 revision: 4425ad9e484976de55891ae77e17d7c1ac0b5ec2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 16:09:06.409171) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 16:09:06.409709) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 16:09:06.410077) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 16:09:06.434709) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:09:06.435087) ========= 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.334yaFM6YT/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.lJQQkX81Xt/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:09:06-- 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% 18.3M=0.001s 2016-07-19 16:09:06 (18.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087769 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:09:06.545055) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:09:06.545384) ========= 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.334yaFM6YT/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.lJQQkX81Xt/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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.024990 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:09:06.593710) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 16:09:06.594067) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 4425ad9e484976de55891ae77e17d7c1ac0b5ec2 --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 4425ad9e484976de55891ae77e17d7c1ac0b5ec2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.334yaFM6YT/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.lJQQkX81Xt/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:09:06,677 truncating revision to first 12 chars 2016-07-19 16:09:06,677 Setting DEBUG logging. 2016-07-19 16:09:06,677 attempt 1/10 2016-07-19 16:09:06,677 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/4425ad9e4849?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 16:09:08,348 unpacking tar archive at: autoland-4425ad9e4849/testing/mozharness/ program finished with exit code 0 elapsedTime=1.957208 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 16:09:08.572872) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 16:09:08.573202) ========= script_repo_revision: 4425ad9e484976de55891ae77e17d7c1ac0b5ec2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 16:09:08.573577) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 16:09:08.573839) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 16:09:08.591681) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-07-19 16:09:08.591986) ========= /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.334yaFM6YT/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.lJQQkX81Xt/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:09:08 INFO - MultiFileLogger online at 20160719 16:09:08 in /builds/slave/test 16:09:08 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 16:09:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:09:08 INFO - {'append_to_log': False, 16:09:08 INFO - 'base_work_dir': '/builds/slave/test', 16:09:08 INFO - 'blob_upload_branch': 'Autoland', 16:09:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:09:08 INFO - 'branch': 'Autoland', 16:09:08 INFO - 'buildbot_json_path': 'buildprops.json', 16:09:08 INFO - 'config_files': ('talos/mac_config.py',), 16:09:08 INFO - 'default_actions': ('clobber', 16:09:08 INFO - 'read-buildbot-config', 16:09:08 INFO - 'download-and-extract', 16:09:08 INFO - 'populate-webroot', 16:09:08 INFO - 'create-virtualenv', 16:09:08 INFO - 'install', 16:09:08 INFO - 'run-tests'), 16:09:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:09:08 INFO - 'download_minidump_stackwalk': True, 16:09:08 INFO - 'download_symbols': 'ondemand', 16:09:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:09:08 INFO - 'tooltool.py': '/tools/tooltool.py', 16:09:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:09:08 INFO - '/tools/misc-python/virtualenv.py')}, 16:09:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:09:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:09:08 INFO - 'installer_path': 'installer.exe', 16:09:08 INFO - 'log_level': 'info', 16:09:08 INFO - 'log_name': 'talos', 16:09:08 INFO - 'log_to_console': True, 16:09:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:09:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:09:08 INFO - 'opt_config_files': (), 16:09:08 INFO - 'pip_index': False, 16:09:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:09:08 INFO - 'cmd': ('bash', 16:09:08 INFO - '-c', 16:09:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:09:08 INFO - 'enabled': True, 16:09:08 INFO - 'halt_on_failure': False, 16:09:08 INFO - 'name': 'check_screen_resolution'},), 16:09:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:09:08 INFO - 'cmd': ('bash', 16:09:08 INFO - '-c', 16:09:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:09:08 INFO - 'enabled': True, 16:09:08 INFO - 'halt_on_failure': False, 16:09:08 INFO - 'name': 'check_screen_resolution'},), 16:09:08 INFO - 'run_cmd_checks_enabled': True, 16:09:08 INFO - 'sps_profile': False, 16:09:08 INFO - 'sps_profile_interval': 0, 16:09:08 INFO - 'suite': 'dromaeojs', 16:09:08 INFO - 'system_bits': '32', 16:09:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:09:08 INFO - 'title': 't-yosemite-r7-0346', 16:09:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:09:08 INFO - 'use_talos_json': True, 16:09:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:09:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:09:08 INFO - 'work_dir': 'build'} 16:09:08 INFO - [mozharness: 2016-07-19 23:09:08.783894Z] Running clobber step. 16:09:08 INFO - Running pre-action listener: _resource_record_pre_action 16:09:08 INFO - Running main action method: clobber 16:09:08 INFO - rmtree: /builds/slave/test/build 16:09:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:09:10 INFO - Running post-action listener: _resource_record_post_action 16:09:10 INFO - [mozharness: 2016-07-19 23:09:10.864748Z] Finished clobber step (success) 16:09:10 INFO - [mozharness: 2016-07-19 23:09:10.865038Z] Running read-buildbot-config step. 16:09:10 INFO - Running pre-action listener: _resource_record_pre_action 16:09:10 INFO - Running main action method: read_buildbot_config 16:09:10 INFO - Using buildbot properties: 16:09:10 INFO - { 16:09:10 INFO - "project": "", 16:09:10 INFO - "product": "firefox", 16:09:10 INFO - "script_repo_revision": "production", 16:09:10 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 16:09:10 INFO - "repository": "", 16:09:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 16:09:10 INFO - "buildid": "20160719151524", 16:09:10 INFO - "pgo_build": "False", 16:09:10 INFO - "basedir": "/builds/slave/test", 16:09:10 INFO - "buildnumber": 52, 16:09:10 INFO - "slavename": "t-yosemite-r7-0346", 16:09:10 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:09:10 INFO - "platform": "yosemite_r7", 16:09:10 INFO - "scheduler": "tests-autoland-macosx64-talos", 16:09:10 INFO - "branch": "autoland", 16:09:10 INFO - "repo_path": "integration/autoland", 16:09:10 INFO - "revision": "4425ad9e484976de55891ae77e17d7c1ac0b5ec2", 16:09:10 INFO - "stage_platform": "macosx64", 16:09:10 INFO - "builduid": "f1c744dfc63c4cdf8e8600ad90b987f8", 16:09:10 INFO - "slavebuilddir": "test" 16:09:10 INFO - } 16:09:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 16:09:10 INFO - Running post-action listener: _resource_record_post_action 16:09:10 INFO - [mozharness: 2016-07-19 23:09:10.870381Z] Finished read-buildbot-config step (success) 16:09:10 INFO - [mozharness: 2016-07-19 23:09:10.870571Z] Running download-and-extract step. 16:09:10 INFO - Running pre-action listener: _resource_record_pre_action 16:09:10 INFO - Running main action method: download_and_extract 16:09:10 INFO - mkdir: /builds/slave/test/build/tests 16:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:10 INFO - https://queue.taskcluster.net/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:09:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:09:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:09:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/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 16:09:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/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 16:09:12 INFO - Downloaded 1199 bytes. 16:09:12 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:09:12 INFO - Using the following test package requirements: 16:09:12 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:09:12 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:09:12 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 16:09:12 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:09:12 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 16:09:12 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:09:12 INFO - u'jsshell-mac.zip'], 16:09:12 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:09:12 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 16:09:12 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:09:12 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:09:12 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 16:09:12 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:09:12 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 16:09:12 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:09:12 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 16:09:12 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:09:12 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 16:09:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:09:12 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')]} 16:09:12 INFO - https://queue.taskcluster.net/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:09:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:09:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:09:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/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 16:09:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/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 16:09:15 INFO - Downloaded 18835961 bytes. 16:09:15 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'] 16:09:15 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 16:09:19 INFO - Return code: 0 16:09:19 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 16:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:19 INFO - https://queue.taskcluster.net/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:09:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:09:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:09:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/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 16:09:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/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 16:09:20 INFO - Downloaded 18835961 bytes. 16:09:20 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'] 16:09:20 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 16:09:23 INFO - Return code: 0 16:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:23 INFO - https://queue.taskcluster.net/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:09:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:09:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:09:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/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 16:09:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/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 16:09:24 INFO - Downloaded 14403317 bytes. 16:09:24 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'] 16:09:24 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 16:09:25 INFO - Return code: 0 16:09: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')]} 16:09:25 INFO - https://queue.taskcluster.net/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:09:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:09:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:09:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 16:09:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UNuaa5PaSdCAnCAwUu4wIA/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 16:09:28 INFO - Downloaded 112929370 bytes. 16:09:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:09:28 INFO - mkdir: /builds/slave/test/properties 16:09:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:09:28 INFO - Writing to file /builds/slave/test/properties/build_url 16:09:28 INFO - Contents: 16:09:28 INFO - build_url:https://queue.taskcluster.net/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:09:29 INFO - Running post-action listener: _resource_record_post_action 16:09:29 INFO - Running post-action listener: set_extra_try_arguments 16:09:29 INFO - [mozharness: 2016-07-19 23:09:29.177180Z] Finished download-and-extract step (success) 16:09:29 INFO - [mozharness: 2016-07-19 23:09:29.177370Z] Running populate-webroot step. 16:09:29 INFO - Running pre-action listener: _resource_record_pre_action 16:09:29 INFO - Running main action method: populate_webroot 16:09:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:09:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:09:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:09:29 INFO - '--tppagecycles', 16:09:29 INFO - '7'], 16:09:29 INFO - 'tests': ['tsvgm']}}, 16:09:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:09:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:09:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:09:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:09:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:09:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:09:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:29 INFO - 'talos_options': ['--disable-e10s'], 16:09:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:09:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:09:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:29 INFO - 'talos_options': ['--disable-e10s'], 16:09:29 INFO - 'tests': ['damp', 'tps']}, 16:09:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:29 INFO - 'tests': ['damp', 'tps']}, 16:09:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:09:29 INFO - 'tests': ['dromaeo_dom']}, 16:09:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:09:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:09:29 INFO - 'tests': ['basic_compositor_video']}, 16:09:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:09:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:09:29 INFO - 'tests': ['a11yr', 16:09:29 INFO - 'ts_paint', 16:09:29 INFO - 'tpaint', 16:09:29 INFO - 'sessionrestore', 16:09:29 INFO - 'sessionrestore_no_auto_restore', 16:09:29 INFO - 'tabpaint']}, 16:09:29 INFO - 'other-e10s': {'tests': ['a11yr', 16:09:29 INFO - 'ts_paint', 16:09:29 INFO - 'tpaint', 16:09:29 INFO - 'sessionrestore', 16:09:29 INFO - 'sessionrestore_no_auto_restore', 16:09:29 INFO - 'tabpaint']}, 16:09:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:09:29 INFO - 'ts_paint', 16:09:29 INFO - 'tpaint', 16:09:29 INFO - 'sessionrestore', 16:09:29 INFO - 'sessionrestore_no_auto_restore', 16:09:29 INFO - 'tabpaint']}, 16:09:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:09:29 INFO - 'ts_paint', 16:09:29 INFO - 'tpaint', 16:09:29 INFO - 'sessionrestore', 16:09:29 INFO - 'sessionrestore_no_auto_restore', 16:09:29 INFO - 'tabpaint']}, 16:09:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:09:29 INFO - 'tests': ['a11yr', 16:09:29 INFO - 'ts_paint', 16:09:29 INFO - 'tpaint', 16:09:29 INFO - 'sessionrestore', 16:09:29 INFO - 'sessionrestore_no_auto_restore', 16:09:29 INFO - 'tabpaint']}, 16:09:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:09:29 INFO - 'tests': ['a11yr', 16:09:29 INFO - 'ts_paint', 16:09:29 INFO - 'tpaint', 16:09:29 INFO - 'sessionrestore', 16:09:29 INFO - 'sessionrestore_no_auto_restore', 16:09:29 INFO - 'tabpaint']}, 16:09:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:09:29 INFO - 'tests': ['tsvgx', 16:09:29 INFO - 'tsvgr_opacity', 16:09:29 INFO - 'tart', 16:09:29 INFO - 'tscrollx', 16:09:29 INFO - 'cart']}, 16:09:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:09:29 INFO - 'tsvgr_opacity', 16:09:29 INFO - 'tart', 16:09:29 INFO - 'tscrollx', 16:09:29 INFO - 'cart']}, 16:09:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:29 INFO - 'talos_options': ['--disable-e10s'], 16:09:29 INFO - 'tests': ['tp5o']}, 16:09:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:29 INFO - 'tests': ['tp5o']}, 16:09:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:29 INFO - 'talos_options': ['--disable-e10s', 16:09:29 INFO - '--xperf_path', 16:09:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:09:29 INFO - 'tests': ['tp5n']}, 16:09:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:29 INFO - 'talos_options': ['--xperf_path', 16:09:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:09:29 INFO - 'tests': ['tp5n']}}, 16:09:29 INFO - 'talos.zip': {'path': '', 16:09:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:09:29 INFO - Running post-action listener: _resource_record_post_action 16:09:29 INFO - [mozharness: 2016-07-19 23:09:29.196638Z] Finished populate-webroot step (success) 16:09:29 INFO - [mozharness: 2016-07-19 23:09:29.196752Z] Running create-virtualenv step. 16:09:29 INFO - Running pre-action listener: _resource_record_pre_action 16:09:29 INFO - Running main action method: create_virtualenv 16:09:29 INFO - Creating virtualenv /builds/slave/test/build/venv 16:09:29 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 16:09:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:09:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:09:29 INFO - Using real prefix '/tools/python27' 16:09:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:09:30 INFO - Installing distribute.............................................................................................................................................................................................done. 16:09:32 INFO - Installing pip.................done. 16:09:32 INFO - Return code: 0 16:09:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:09: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')]} 16:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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')]} 16:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100879a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10090e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c31555820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007ddd30>, '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 0x100542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007d5b20>, '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.334yaFM6YT/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.lJQQkX81Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:09: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', 'pip>=1.5'] in /builds/slave/test/build 16:09: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 pip>=1.5 16:09:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.334yaFM6YT/Render', 16:09:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:32 INFO - 'HOME': '/Users/cltbld', 16:09:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:32 INFO - 'LOGNAME': 'cltbld', 16:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:32 INFO - 'MOZ_NO_REMOTE': '1', 16:09:32 INFO - 'NO_EM_RESTART': '1', 16:09:32 INFO - 'PAGER': '/bin/cat', 16:09:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:32 INFO - 'PWD': '/builds/slave/test', 16:09:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:32 INFO - 'SHELL': '/bin/bash', 16:09:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lJQQkX81Xt/Listeners', 16:09:32 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:09:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:32 INFO - 'USER': 'cltbld', 16:09:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:32 INFO - 'XPC_FLAGS': '0x0', 16:09:32 INFO - 'XPC_SERVICE_NAME': '0', 16:09:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:33 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 16:09:33 INFO - Cleaning up... 16:09:33 INFO - Return code: 0 16:09:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100879a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10090e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c31555820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007ddd30>, '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 0x100542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007d5b20>, '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.334yaFM6YT/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.lJQQkX81Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:09:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.334yaFM6YT/Render', 16:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:33 INFO - 'HOME': '/Users/cltbld', 16:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:33 INFO - 'LOGNAME': 'cltbld', 16:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:33 INFO - 'MOZ_NO_REMOTE': '1', 16:09:33 INFO - 'NO_EM_RESTART': '1', 16:09:33 INFO - 'PAGER': '/bin/cat', 16:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:33 INFO - 'PWD': '/builds/slave/test', 16:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:33 INFO - 'SHELL': '/bin/bash', 16:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lJQQkX81Xt/Listeners', 16:09:33 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:33 INFO - 'USER': 'cltbld', 16:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:33 INFO - 'XPC_FLAGS': '0x0', 16:09:33 INFO - 'XPC_SERVICE_NAME': '0', 16:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:33 INFO - Downloading/unpacking psutil>=3.1.1 16:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:09:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:09:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:09:36 INFO - Installing collected packages: psutil 16:09:36 INFO - Running setup.py install for psutil 16:09:36 INFO - building 'psutil._psutil_osx' extension 16:09:36 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 16:09:37 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 16:09:37 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 16:09:37 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 16:09:37 INFO - building 'psutil._psutil_posix' extension 16:09:37 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 16:09:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:09:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:09:37 INFO - ^ 16:09:37 INFO - 1 warning generated. 16:09:37 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 16:09:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:09:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:09:37 INFO - Successfully installed psutil 16:09:37 INFO - Cleaning up... 16:09:37 INFO - Return code: 0 16:09:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:37 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 0x100879a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10090e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c31555820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007ddd30>, '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 0x100542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007d5b20>, '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.334yaFM6YT/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.lJQQkX81Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:09:37 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 16:09:37 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 16:09:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.334yaFM6YT/Render', 16:09:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:37 INFO - 'HOME': '/Users/cltbld', 16:09:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:37 INFO - 'LOGNAME': 'cltbld', 16:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:37 INFO - 'MOZ_NO_REMOTE': '1', 16:09:37 INFO - 'NO_EM_RESTART': '1', 16:09:37 INFO - 'PAGER': '/bin/cat', 16:09:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:37 INFO - 'PWD': '/builds/slave/test', 16:09:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:37 INFO - 'SHELL': '/bin/bash', 16:09:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lJQQkX81Xt/Listeners', 16:09:37 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:09:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:37 INFO - 'USER': 'cltbld', 16:09:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:37 INFO - 'XPC_FLAGS': '0x0', 16:09:37 INFO - 'XPC_SERVICE_NAME': '0', 16:09:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:09:37 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 16:09:37 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 16:09:37 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 16:09:37 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 16:09:37 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 16:09:37 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 16:09:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:09:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:09:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:09:40 INFO - Installing collected packages: mozsystemmonitor 16:09:40 INFO - Running setup.py install for mozsystemmonitor 16:09:40 INFO - Successfully installed mozsystemmonitor 16:09:40 INFO - Cleaning up... 16:09:40 INFO - Return code: 0 16:09:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:09: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')]} 16:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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')]} 16:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:40 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 0x100879a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10090e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c31555820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007ddd30>, '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 0x100542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007d5b20>, '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.334yaFM6YT/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.lJQQkX81Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:09:40 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 16:09:40 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 16:09:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.334yaFM6YT/Render', 16:09:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:40 INFO - 'HOME': '/Users/cltbld', 16:09:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:40 INFO - 'LOGNAME': 'cltbld', 16:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:40 INFO - 'MOZ_NO_REMOTE': '1', 16:09:40 INFO - 'NO_EM_RESTART': '1', 16:09:40 INFO - 'PAGER': '/bin/cat', 16:09:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:40 INFO - 'PWD': '/builds/slave/test', 16:09:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:40 INFO - 'SHELL': '/bin/bash', 16:09:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lJQQkX81Xt/Listeners', 16:09:40 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:09:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:40 INFO - 'USER': 'cltbld', 16:09:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:40 INFO - 'XPC_FLAGS': '0x0', 16:09:40 INFO - 'XPC_SERVICE_NAME': '0', 16:09:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:40 INFO - Downloading/unpacking jsonschema==2.5.1 16:09:40 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 16:09:40 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 16:09:40 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 16:09:40 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 16:09:40 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 16:09:40 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 16:09:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:09:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:09:43 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 16:09:43 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 16:09:43 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 16:09:43 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 16:09:43 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 16:09:43 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 16:09:43 INFO - Downloading functools32-3.2.3-2.tar.gz 16:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:09:43 INFO - warning: no files found matching '*.txt' 16:09:43 INFO - no previously-included directories found matching 'build' 16:09:43 INFO - no previously-included directories found matching 'dist' 16:09:43 INFO - no previously-included directories found matching '.git*' 16:09:43 INFO - Installing collected packages: jsonschema, functools32 16:09:43 INFO - Running setup.py install for functools32 16:09:43 INFO - warning: no files found matching '*.txt' 16:09:43 INFO - no previously-included directories found matching 'build' 16:09:43 INFO - no previously-included directories found matching 'dist' 16:09:43 INFO - no previously-included directories found matching '.git*' 16:09:43 INFO - Successfully installed jsonschema functools32 16:09:43 INFO - Cleaning up... 16:09:44 INFO - Return code: 0 16:09:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:09:44 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')]} 16:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:44 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')]} 16:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:44 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 0x100879a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10090e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c31555820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007ddd30>, '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 0x100542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007d5b20>, '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.334yaFM6YT/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.lJQQkX81Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:09:44 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 16:09:44 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 16:09:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.334yaFM6YT/Render', 16:09:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:44 INFO - 'HOME': '/Users/cltbld', 16:09:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:44 INFO - 'LOGNAME': 'cltbld', 16:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:44 INFO - 'MOZ_NO_REMOTE': '1', 16:09:44 INFO - 'NO_EM_RESTART': '1', 16:09:44 INFO - 'PAGER': '/bin/cat', 16:09:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:44 INFO - 'PWD': '/builds/slave/test', 16:09:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:44 INFO - 'SHELL': '/bin/bash', 16:09:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lJQQkX81Xt/Listeners', 16:09:44 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:09:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:44 INFO - 'USER': 'cltbld', 16:09:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:44 INFO - 'XPC_FLAGS': '0x0', 16:09:44 INFO - 'XPC_SERVICE_NAME': '0', 16:09:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:44 INFO - Downloading/unpacking blobuploader==1.2.4 16:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:46 INFO - Downloading blobuploader-1.2.4.tar.gz 16:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:09:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:09:46 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 16:09:46 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 16:09:46 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 16:09:46 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 16:09:46 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 16:09:46 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 16:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:09:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:09: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 16:09: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 16:09: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 16:09: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 16:09: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 16:09: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 16:09:47 INFO - Downloading docopt-0.6.1.tar.gz 16:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:09:48 INFO - Installing collected packages: blobuploader, requests, docopt 16:09:48 INFO - Running setup.py install for blobuploader 16:09:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:09:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:09:48 INFO - Running setup.py install for requests 16:09:48 INFO - Running setup.py install for docopt 16:09:48 INFO - Successfully installed blobuploader requests docopt 16:09:48 INFO - Cleaning up... 16:09:48 INFO - Return code: 0 16:09:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:48 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 0x100879a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10090e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c31555820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007ddd30>, '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 0x100542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007d5b20>, '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.334yaFM6YT/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.lJQQkX81Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:09:48 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 16:09:48 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 16:09:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.334yaFM6YT/Render', 16:09:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:48 INFO - 'HOME': '/Users/cltbld', 16:09:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:48 INFO - 'LOGNAME': 'cltbld', 16:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:48 INFO - 'MOZ_NO_REMOTE': '1', 16:09:48 INFO - 'NO_EM_RESTART': '1', 16:09:48 INFO - 'PAGER': '/bin/cat', 16:09:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:48 INFO - 'PWD': '/builds/slave/test', 16:09:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:48 INFO - 'SHELL': '/bin/bash', 16:09:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lJQQkX81Xt/Listeners', 16:09:48 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:09:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:48 INFO - 'USER': 'cltbld', 16:09:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:48 INFO - 'XPC_FLAGS': '0x0', 16:09:48 INFO - 'XPC_SERVICE_NAME': '0', 16:09:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:09:49 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-tXINfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:09:49 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-9dQ5jL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:09:49 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-EVDZq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:09:49 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-f1c9gA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:09:49 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-KCE5T9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:09:49 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-MJD6gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:09:49 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-DTObvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:09:49 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-zqZA4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:09:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-kYanRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:09:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-uBsaAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:09:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-mO8SFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:09:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-zUdN0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:09:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-dscBwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:09:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-157ddr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-K0v8bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:09:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ZStudT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:09:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-U4Ffks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:09:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:09:50 INFO - Running setup.py install for manifestparser 16:09:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:09:51 INFO - Running setup.py install for mozcrash 16:09:51 INFO - Running setup.py install for mozdebug 16:09:51 INFO - Running setup.py install for mozdevice 16:09:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:09:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:09:51 INFO - Running setup.py install for mozfile 16:09:51 INFO - Running setup.py install for mozhttpd 16:09:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:09:51 INFO - Running setup.py install for mozinfo 16:09:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:09:52 INFO - Running setup.py install for mozInstall 16:09:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:09:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:09:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:09:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:09:52 INFO - Running setup.py install for mozleak 16:09:52 INFO - Running setup.py install for mozlog 16:09:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:09:52 INFO - Running setup.py install for moznetwork 16:09:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:09:52 INFO - Running setup.py install for mozprocess 16:09:52 INFO - Running setup.py install for mozprofile 16:09:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:09:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:09:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:09:52 INFO - Running setup.py install for mozrunner 16:09:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:09:53 INFO - Running setup.py install for mozscreenshot 16:09:53 INFO - Running setup.py install for moztest 16:09:53 INFO - Running setup.py install for mozversion 16:09:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:09:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:09:53 INFO - Cleaning up... 16:09:53 INFO - Return code: 0 16:09:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:09:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:53 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 0x100879a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10090e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c31555820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007ddd30>, '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 0x100542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007d5b20>, '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.334yaFM6YT/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.lJQQkX81Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:09:53 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 16:09:53 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 16:09:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.334yaFM6YT/Render', 16:09:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:53 INFO - 'HOME': '/Users/cltbld', 16:09:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:53 INFO - 'LOGNAME': 'cltbld', 16:09:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:53 INFO - 'MOZ_NO_REMOTE': '1', 16:09:53 INFO - 'NO_EM_RESTART': '1', 16:09:53 INFO - 'PAGER': '/bin/cat', 16:09:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:53 INFO - 'PWD': '/builds/slave/test', 16:09:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:53 INFO - 'SHELL': '/bin/bash', 16:09:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lJQQkX81Xt/Listeners', 16:09:53 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:09:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:53 INFO - 'USER': 'cltbld', 16:09:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:53 INFO - 'XPC_FLAGS': '0x0', 16:09:53 INFO - 'XPC_SERVICE_NAME': '0', 16:09:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:09:53 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-HsFs21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:09:53 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)) 16:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:09:53 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-JYdPV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:09:54 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)) 16:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:09:54 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-hyt9Vp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:09:54 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)) 16:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:09:54 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-9FAkLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:09:54 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)) 16:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:09:54 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-NkidfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:09:54 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)) 16:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:09:54 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-MABKsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:09:54 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)) 16:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:09:54 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-sWqWmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:09:54 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)) 16:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:09:54 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-61o2De-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:09:54 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)) 16:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:09:54 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-_IZ97r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:09:54 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)) 16:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:09:54 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-X6Mqqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:09:54 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)) 16:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:09:54 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-EDQIkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:09:54 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)) 16:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:09:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-HHcqNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:09:55 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)) 16:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:09:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-TgMlUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:09:55 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)) 16:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:09:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Vhoq3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:09:55 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)) 16:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-omQv6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:55 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)) 16:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:09:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-4kvuPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:09:55 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)) 16:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:09:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-KYSsTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:09:55 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)) 16:09:55 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)) 16:09:55 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)) 16:09:55 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)) 16:09:55 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)) 16:09:55 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)) 16:09:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:09:55 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 16:09:55 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 16:09:55 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 16:09:55 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 16:09:55 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 16:09:55 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 16:09:58 INFO - Downloading blessings-1.6.tar.gz 16:09:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:09:58 INFO - Installing collected packages: blessings 16:09:58 INFO - Running setup.py install for blessings 16:09:58 INFO - Successfully installed blessings 16:09:58 INFO - Cleaning up... 16:09:58 INFO - Return code: 0 16:09:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:09:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:09:58 INFO - Reading from file tmpfile_stdout 16:09:58 INFO - Current package versions: 16:09:58 INFO - blessings == 1.6 16:09:58 INFO - blobuploader == 1.2.4 16:09:58 INFO - docopt == 0.6.1 16:09:58 INFO - functools32 == 3.2.3-2 16:09:58 INFO - jsonschema == 2.5.1 16:09:58 INFO - manifestparser == 1.1 16:09:58 INFO - mozInstall == 1.12 16:09:58 INFO - mozcrash == 0.17 16:09:58 INFO - mozdebug == 0.1 16:09:58 INFO - mozdevice == 0.48 16:09:58 INFO - mozfile == 1.2 16:09:58 INFO - mozhttpd == 0.7 16:09:58 INFO - mozinfo == 0.9 16:09:58 INFO - mozleak == 0.1 16:09:58 INFO - mozlog == 3.3 16:09:58 INFO - moznetwork == 0.27 16:09:58 INFO - mozprocess == 0.23 16:09:58 INFO - mozprofile == 0.28 16:09:58 INFO - mozrunner == 6.12 16:09:58 INFO - mozscreenshot == 0.1 16:09:58 INFO - mozsystemmonitor == 0.3 16:09:58 INFO - moztest == 0.7 16:09:58 INFO - mozversion == 1.4 16:09:58 INFO - psutil == 3.1.1 16:09:58 INFO - requests == 1.2.3 16:09:58 INFO - wsgiref == 0.1.2 16:09:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:58 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 0x100879a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10090e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c31555820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007ddd30>, '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 0x100542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007d5b20>, '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.334yaFM6YT/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.lJQQkX81Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:09:58 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 16:09:58 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 16:09:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.334yaFM6YT/Render', 16:09:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:58 INFO - 'HOME': '/Users/cltbld', 16:09:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:58 INFO - 'LOGNAME': 'cltbld', 16:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:58 INFO - 'MOZ_NO_REMOTE': '1', 16:09:58 INFO - 'NO_EM_RESTART': '1', 16:09:58 INFO - 'PAGER': '/bin/cat', 16:09:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:58 INFO - 'PWD': '/builds/slave/test', 16:09:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:58 INFO - 'SHELL': '/bin/bash', 16:09:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lJQQkX81Xt/Listeners', 16:09:58 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:09:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:58 INFO - 'USER': 'cltbld', 16:09:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:58 INFO - 'XPC_FLAGS': '0x0', 16:09:58 INFO - 'XPC_SERVICE_NAME': '0', 16:09:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:58 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)) 16:09:58 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)) 16:09:58 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)) 16:09:58 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)) 16:09:58 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)) 16:09:58 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)) 16:09:58 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)) 16:09:58 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)) 16:09:58 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)) 16:09:58 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)) 16:09:58 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)) 16:09:58 INFO - Cleaning up... 16:09:58 INFO - Return code: 0 16:09:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100879a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10090e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c31555820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007ddd30>, '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 0x100542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007d5b20>, '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.334yaFM6YT/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.lJQQkX81Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:09:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:09:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.334yaFM6YT/Render', 16:09:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:58 INFO - 'HOME': '/Users/cltbld', 16:09:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:58 INFO - 'LOGNAME': 'cltbld', 16:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:58 INFO - 'MOZ_NO_REMOTE': '1', 16:09:58 INFO - 'NO_EM_RESTART': '1', 16:09:58 INFO - 'PAGER': '/bin/cat', 16:09:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:58 INFO - 'PWD': '/builds/slave/test', 16:09:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:58 INFO - 'SHELL': '/bin/bash', 16:09:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lJQQkX81Xt/Listeners', 16:09:58 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:09:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:58 INFO - 'USER': 'cltbld', 16:09:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:58 INFO - 'XPC_FLAGS': '0x0', 16:09:58 INFO - 'XPC_SERVICE_NAME': '0', 16:09:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:09:59 INFO - Cleaning up... 16:09:59 INFO - Return code: 0 16:09:59 INFO - Running post-action listener: _resource_record_post_action 16:09:59 INFO - Running post-action listener: _start_resource_monitoring 16:09:59 INFO - Starting resource monitoring. 16:09:59 INFO - [mozharness: 2016-07-19 23:09:59.251288Z] Finished create-virtualenv step (success) 16:09:59 INFO - [mozharness: 2016-07-19 23:09:59.252207Z] Running install step. 16:09:59 INFO - Running pre-action listener: _resource_record_pre_action 16:09:59 INFO - Running main action method: install 16:09:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:09:59 INFO - Reading from file tmpfile_stdout 16:09:59 INFO - Detecting whether we're running mozinstall >=1.0... 16:09:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:09:59 INFO - Reading from file tmpfile_stdout 16:09:59 INFO - Output received: 16:09:59 INFO - Usage: mozinstall [options] installer 16:09:59 INFO - Options: 16:09:59 INFO - -h, --help show this help message and exit 16:09:59 INFO - -d DEST, --destination=DEST 16:09:59 INFO - Directory to install application into. [default: 16:09:59 INFO - "/builds/slave/test"] 16:09:59 INFO - --app=APP Application being installed. [default: firefox] 16:09:59 INFO - mkdir: /builds/slave/test/build/application 16:09:59 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'] 16:09:59 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 16:10:34 INFO - Reading from file tmpfile_stdout 16:10:34 INFO - Output received: 16:10:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:10:34 INFO - Running post-action listener: _resource_record_post_action 16:10:34 INFO - [mozharness: 2016-07-19 23:10:34.064867Z] Finished install step (success) 16:10:34 INFO - [mozharness: 2016-07-19 23:10:34.064984Z] Running run-tests step. 16:10:34 INFO - Running pre-action listener: _resource_record_pre_action 16:10:34 INFO - Running main action method: run_tests 16:10:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:10:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:10:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:10:34 INFO - 2016-07-19 16:10:34.079 screenresolution[1634:7431] starting screenresolution argv=screenresolution get 16:10:34 INFO - 2016-07-19 16:10:34.082 screenresolution[1634:7431] Display 0: 1600x1200x32@60 16:10:34 INFO - 2016-07-19 16:10:34.088 screenresolution[1635:7433] starting screenresolution argv=screenresolution list 16:10:34 INFO - Available Modes on Display 0 16:10:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:10:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:10:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:10:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:10:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:10:34 INFO - Intel Iris: 16:10:34 INFO - Chipset Model: Intel Iris 16:10:34 INFO - Type: GPU 16:10:34 INFO - Bus: Built-In 16:10:34 INFO - VRAM (Dynamic, Max): 1536 MB 16:10:34 INFO - Vendor: Intel (0x8086) 16:10:34 INFO - Device ID: 0x0a2e 16:10:34 INFO - Revision ID: 0x0009 16:10:34 INFO - Displays: 16:10:34 INFO - Display: 16:10:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:10:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:10:34 INFO - Display Serial Number: 1600x1200 60 16:10:34 INFO - Main Display: Yes 16:10:34 INFO - Mirror: Off 16:10:34 INFO - Online: Yes 16:10:34 INFO - Rotation: Supported 16:10:34 INFO - Return code: 0 16:10:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:10:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:10:34 INFO - Python 2.7.3 16:10:34 INFO - Return code: 0 16:10:34 INFO - grabbing minidump binary from tooltool 16:10:34 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')]} 16:10:34 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 0x1007ddd30>, '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 0x100542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007d5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:10:34 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 16:10:34 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 16:10:34 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 16:10:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:10:34 INFO - Return code: 0 16:10:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:10:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:10:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:10:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:10:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:10:34 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/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0346', '--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 16:10:34 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/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0346 --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 16:10:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.334yaFM6YT/Render', 16:10:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:34 INFO - 'HOME': '/Users/cltbld', 16:10:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:34 INFO - 'LOGNAME': 'cltbld', 16:10:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:10:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:10:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:34 INFO - 'MOZ_NO_REMOTE': '1', 16:10:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:10:34 INFO - 'NO_EM_RESTART': '1', 16:10:34 INFO - 'PAGER': '/bin/cat', 16:10:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:34 INFO - 'PWD': '/builds/slave/test', 16:10:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:10:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:34 INFO - 'SHELL': '/bin/bash', 16:10:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lJQQkX81Xt/Listeners', 16:10:34 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:10:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:34 INFO - 'USER': 'cltbld', 16:10:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:34 INFO - 'XPC_FLAGS': '0x0', 16:10:34 INFO - 'XPC_SERVICE_NAME': '0', 16:10:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:34 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/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0346', '--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 16:10:34 INFO - mozversion application_buildid: 20160719151524 16:10:34 INFO - mozversion application_changeset: 4425ad9e484976de55891ae77e17d7c1ac0b5ec2 16:10:34 INFO - mozversion application_display_name: Nightly 16:10:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:10:34 INFO - mozversion application_name: Firefox 16:10:34 INFO - mozversion application_remotingname: firefox 16:10:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:10:34 INFO - mozversion application_vendor: Mozilla 16:10:34 INFO - mozversion application_version: 50.0a1 16:10:34 INFO - mozversion platform_buildid: 20160719151524 16:10:34 INFO - mozversion platform_changeset: 4425ad9e484976de55891ae77e17d7c1ac0b5ec2 16:10:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:10:34 INFO - mozversion platform_version: 50.0a1 16:10:34 INFO - using testdate: 1468969834 16:10:34 INFO - actual date: 1468969834 16:10:34 INFO - starting webserver on 'localhost:49272' 16:10:34 INFO - SUITE-START | Running 2 tests 16:10:34 INFO - TEST-START | dromaeo_css 16:10:34 INFO - operating with platform_type : mac_ 16:10:34 INFO - Initialising browser for dromaeo_css test... 16:10:34 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmp2w4C1C/profile http://localhost:49272/getInfo.html) 16:10:36 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 16:10:36 INFO - PROCESS | 1645 | colorDepth:24 16:10:36 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 16:10:36 INFO - PROCESS | 1645 | __metrics 16:10:37 INFO - PROCESS | 1645 | 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" 16:10:37 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:10:37 INFO - TEST-INFO | 1645: exit 0 16:10:37 INFO - Browser initialized. 16:10:37 INFO - Running cycle 1/1 for dromaeo_css test... 16:10:37 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmp2w4C1C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:10:49 INFO - PROCESS | 1647 | RSS: Main: 245051392 16:10:49 INFO - PROCESS | 1647 | 16:11:41 INFO - PROCESS | 1647 | 1468969901280 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] 16:13:51 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 16:13:53 INFO - PROCESS | 1647 | RSS: Main: 443281408 16:13:53 INFO - PROCESS | 1647 | 16:16:56 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 16:16:57 INFO - PROCESS | 1647 | RSS: Main: 373727232 16:16:57 INFO - PROCESS | 1647 | 16:19:59 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 16:20:00 INFO - PROCESS | 1647 | RSS: Main: 375914496 16:20:00 INFO - PROCESS | 1647 | 16:23:02 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 16:23:03 INFO - PROCESS | 1647 | RSS: Main: 373211136 16:23:03 INFO - PROCESS | 1647 | 16:26:06 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 16:26:07 INFO - PROCESS | 1647 | RSS: Main: 336384000 16:26:07 INFO - PROCESS | 1647 | 16:29:10 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 16:29:10 INFO - PROCESS | 1647 | RSS: Main: 387133440 16:29:10 INFO - PROCESS | 1647 | 16:29:10 INFO - PROCESS | 1647 | __start_tp_report 16:29:10 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 16:29:10 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 16:29:10 INFO - PROCESS | 1647 | |i|pagename|runs| 16:29:10 INFO - PROCESS | 1647 | |0;dojo.html;3296.0441471973113;3305.206750379909;3329.4339962206423;3341.4152523308417;3387.136280248538;5504.9174262386;5532.004239236936;5567.164925261213;5585.664860108392;5603;5601.019821531236;5639.971800141004;5642.7742890284335;5644.435556444353;5645.1814486899375;5094.898102037966;5137.614678899097;5162.638615296931;5168.7932482700835;5224.4775522447835;5183.870403239912;5193.221016847466;5197.506236907488;5200.505951934579;5215.374155101393;5077.365329333833;5098.031374038921;5106.64253502255;5110.872228194306;5188.377394712621;5156.53591176794;5173.844784656466;5183.870403239949;5191.636585439021;5242.475752424727;5002.199648056294;5028.094942910275;5030.346055012825;5113.948860511385;5141.460146684619;5516.558675305966;5517.779288828442;5569.805056823032;5575.8606034849045;5576.804811831566;6153.507719382484;6158.56890017699;6163.9383606164265;6163.96918015407;6170.96914515429;5587.106063029895;6114.021756518936;6191.845203869894;6198.938010619881;6209.751609935597;5714.057180565202;5720.942790572041;5722.942770572325;5745.080787074048;5754.6547207167705;6300.558960872695;6324.683765811691;6345.524085693499;6349.52378571609;6354.809355719336;5873.20711703923;5936.970315148396;5945.494632956247;6021.759129634853;6084.8783024340155;6178.382161783786;6199.938000620026;6200.689965501707;6209.285932117774;6217.316095229521;6123.020316749299;6128.571000029955;6133.509319254449;6138.508919286535;6149.96925015372;5960.135780311831;5989.281286245591;6162.26052873649;6166;6189.133521307021;4799.064182484382;4832.371791667061;4872.4883887192045;4872.902541949212;4881.804727810919;4353.325234588644;4359.433273674465;4374.606285434326;4396.846110386121;4397.2304846651705;5469.699166545878;5507.256520369779;5526.613137080446;5622.212890195376;5635.1828984796975;6130.795626845359;6172.969135154385;6207.751689932352;6210.72051757672;6220.595661282002;5551.195076713854;5567.081431563746;5568.470995255444;5570.805021824217;5595.468430499096;5195.402528709247;5222.947770522323;5318.734063296897;5320.255164277004;5334.51989320963;6266.154069200599;6319.6208227506495;6323.051542268697;6328.303886572381;6332.461740752087;6206.944819380807;6240.875182496416;6246.875062498633;6249.000159974382;6257.906131408124;6219.191505104308;6261.78083767066;6301.716422761078;6306.337834527309;6354.142190804182;6051.848703782441;6090.360512146338;6091.238595175603;6095.908561371672;6097.237845269341;6169.607058928683;6194.318624951162;6206.72069756853;6208;6228.190335256388;5762.7694892203845;5771.98990176709;5813.6221145625395;5828.59199856006;5966.791162309299;5865.472107510344;5871.7651293947765;5873.060310350324;5875.471207591338;5877.676727779842;5778.653280803165;5812.244408226903;5872.119182122716;5876.94123058781;5881.38245484218;5817.185594016925;6009.248843894513;6017.909731354121;6035.155078288955;6053.213082299273;4837.733924634179;5638.4925356718095;5757.424257574209;5825.3592104867585;5886.293644762656 16:29:10 INFO - PROCESS | 1647 | |1;ext.html;531.5694287627023;531.9387821296514;537.9354477462705;557.9816834277444;563.2818156850016;26879.596806047924;41777.373339399936;42652.573474265206;42994.14011719764;45455.54544454555;44588.1082378353;44807;45149.77425112878;45159.322610160874;45176.54823451764;8854.86717699235;9876.555555000024;10014;10034;10044.497775111251;8823.11768823119;9055;9059.320550958671;9061.864072038892;11881.524739010429;12085.093617978642;12394.008479321677;12479.37603119845;12481.375931203449;12482.313472758995;12190.390480475986;12269.754604907852;12270.38648067593;12281.263124212532;12288.447019884126;8725.650973961034;11931.04551635867;12030.63908082759;12154.027677785756;12175.756484870342;42666.78666606647;43082.56917430817;43093;43193.78403107996;43228.3515747264;3568.6431356864105;3882.839537435628;4145.689073319513;4225.112726327444;4268.146370725867;20744.377668669964;25881.870590646926;29204.8539757302;33144.50849711769;33555.161120972174;3233.2401885556874;3236.2394837213237;3280.0487858521;3310.8344582770765;3355.0605830367535;409140;409718;410869;412496;412506;62005.069923951174;62922.685386572775;62929.370706293266;63146;63165.68417157885;65304.67347663231;65319.67340163269;65497.01754473687;65581;65622.67188664027;16075.758863617055;16833.242504087346;16845.326186952385;16859.915700421418;16892.324307027582;13033.087683862228;13098.2141071536;13112.803307950388;13120.671983200305;13139.60581182566;14951.953363264467;15157.393704251926;16652.75020874688;16781.748273775902;16823.57941051459;6383;7524.629741631368;7573.2805383488485;7593.506422082547;7671.347935425551;921.9861702074475;1015.2740790334946;1024.08856118055;1073.313079629038;1148.4487446025955;25236.369090772878;25358.366040849127;28672.569911451345;30366;31305.68694313073;1061.0874647802884;1107.7482444837283;1117.0951529261324;1118.6196693124377;1222.9266244025387;15904.840951590337;16417;16751;16983.830161698224;17398.565035874206;563457;571252;574499;576015;577438;62017.68991155015;62309;62321;62343.376566233754;62644.686776566734;1306.7125232448846;1334.3861823561076;1423.7223741370747;1447.4282658349894;1481.2445652716976;30536.083917482512;31795.682043179273;32053.557589908978;32481.51277730883;32538.349233014735;51439.48560514347;51886.740566296925;51955;51977;52023;498084.5095774498;498666;498745;498759;499012;24224;24670.383240419134;26025.869870650524;26842.86578567173;27289.863550682912;38860.6113938857;49811.003779924926;49873.25190122223;52787.20819187792;53884;40164;41753.58246417619;42130;42589;43033.784831075645;31846.840765796023;40666.79666601648;40998.795006025975;41905;41931.790341049316;38598.035049123995;39069;39359.212815742954;39565;39896.80051599723;33659.83170084232;34415.13962150966;35221.767238146545;36318;36390.63609363873;25872.48255034851;26146.823392947743;26260.60609090827;26626;26677.333066672712 16:29:10 INFO - PROCESS | 1647 | |2;jquery.html;357.98747043853473;379.22637818849546;382.9770213787171;385.4911516797827;404.60348858119045;15351.385944562218;15435.536933891986;20891.895540522313;20952.37142885712;21235.787642123574;15758.448454304102;20666.89666551665;21102.683459748117;21166.89416552919;21640.02619882105;5460.836174914739;6019.909701354483;6230.657313847737;6237.064440333941;6248.250209974809;6150.753969841201;6183.072539119124;6224.470919971808;6225.875482490347;6226.657533835637;5960.493358064569;6054.273487181545;6132.693365331738;6154.599951003171;6178.876422471548;6007.279126504804;6115.633062016271;6121.449069583737;6125.663088530129;6134.8159555213415;5159.484051594848;5258.947410525884;5260.92108618371;5288.867778305535;5298.947010529884;25511.48977020449;26195.73804261952;26443.33891652705;26470.470590588273;28895.676997145387;2440.9389765255833;2492.1277552856536;2577.4716183182404;2605.5179791738587;2652.485650161881;17247.396341128126;17402.738958915626;17578.38475653359;17823.197956092008;17988.55028624282;1817.7727784026997;1994.3019943019972;2002.4393169912303;2016.0927582587747;2110.9577808443746;119493;142455;150420;153045;153440.2327988364;21098.367048988555;22409.77590224077;22442.32673019812;22551;22651.43371415728;36441.635583644354;36442.63557364445;36458.63541364606;36527.817360913024;36535.63464365376;10846.078083362845;11337.60318388869;11348.432578371048;11358.659240222642;11538.596149134908;9079.409838360485;9097.1812536872;9130.908690913006;9244.306676999251;9289.72130836076;9997.850032249522;10039.196864250842;10047.648332308334;10174.440405777754;10175.592976281014;3112.642046164675;3830.674392676598;3873.3802591585213;3974.841006359771;4044.312466880637;715.9251016882398;794.241499368102;817.3257062923112;927.9257659387367;959.932804703664;20215.49461263451;21165.576688466146;21468.769953423278;21563.460913477287;21569.352919412442;904.1726819959795;974.1622204903778;1017.4607458047246;1021.8875923648393;1024.2318261304022;11013.614523491638;11302.265352752209;11313.773724525463;11330.093592512743;11335.433228338716;598030;605763;612201;616265;617598;108464.91535084706;138320;140669;141260;143735;1017.1252722658508;1018.378788938732;1072.4905669806935;1097.040089921319;1097.780443911221;18074.819251807312;18422.171002305196;18457.077146142907;18597.44207673772;18988.525286867938;44064.77967610142;51469.71325716737;53971;56653.71673141773;57605;78087.6095619541;78313;84756.57621711852;85151;85579.71630425424;7060.54106483077;8366.24703776663;8444.493330400195;8490;8500.064992850905;9560.282978776759;13206.867931320949;13334.13328133707;13355.332233388486;13439.865601344252;11446.141539384746;11448.713782155513;11507.194496385173;11507.424628768695;11603.477843496894;11231.887681123084;11367.20429569922;11398.373089480157;11467.713307167387;11506.07951363872;9313.25493960495;11499.080073593925;11600.30398176112;11764.529418823151;11770.3526306054;7057.470689698396;8254.876176857471;8357.373197010127;8406.537640429835;8445.831083378174;10690.89309106899;11273.661790146309;11507.769844603228;11553.480093395985;11581.88418115808 16:29:10 INFO - PROCESS | 1647 | |3;mootools.html;1551.968960620788;1599.0565566315872;1823.1431227323153;1953.8632295739303;1973.9506512337182;8635.69775057873;8979;9135.908640913589;9136.360454768168;9140.542972851355;7896.447248692594;8113.310368618674;8595.871061934076;8857.557122143884;8931.151540603649;2333.509962907783;2360.433495960974;2392.892319845611;2403.194929698553;2403.927882163529;2361.95276094478;2370.146747171017;2407.8675672837985;2409.2170044735444;2415.2271273192496;2082.1151010820417;2156.568686262746;2159.0500179920864;2159.762426133124;2162.751283602382;2315.8031567316716;2384.546936082139;2386.8925898334614;2397.113068164773;2398.052769156191;2366.3137690069857;2367.633016882369;2375.6340104440014;2389.0682633772844;2392.569337519246;7900.723474678359;8707.564621768886;8935.955320223422;9065.048169942193;9071.863922041175;5170.534651881311;5376.274202982626;5383.73081345935;5384.6769193848495;5394.703291318976;3551.396262635358;3579.355715971124;3620.348337299285;3623.927521449556;3645.2891686121216;1069.3851035654452;1070.7269646240236;1083.0793825248554;1086.8098082835472;1089.0851684584986;12031.578894738643;12114.152009359348;12118.6364409067;12123.151379403445;12157.878421215852;4407.405000324915;4668.95331046692;4861.124997500413;4877.219644856877;4882.731449770296;10356.585736570452;10372.377657340432;10380.117689996285;10380.688579342632;10391.948040259902;5432.728363581805;5437.755301011385;5438.265834112344;5441.374241962225;5458.263134476874;4914.557689807934;4934.802607895644;4966.230234313687;4971.055499455092;4980.228064649913;3866.9226615467533;3880.6507414332846;3923.6468717815533;3936.901577460529;3977.76133431995;1254.8933340666147;1270.1490001698699;1273.6561128495255;1279.174932168746;1285.6271681212343;930.5998420679038;936.6347124621403;942.5475771629656;963.8265112279857;982.6806287956442;4723.126221649006;6079.148919151322;6196.039613859859;6199.504039676815;6237.438630523184;445.5544455544456;456.8081405809568;464.1436549566113;464.4728233455053;466.2958932012618;4048;4059.3505039193587;4108.548059713429;4146.688998325078;4172.4993000840095;10595.311304765166;10641;10993.505292261867;11011.394373309546;11019.393933333744;10934.945325273482;10945.4527273636;10954.178436617127;10963.83554246687;10975.50610222542;7965.283124518705;8259.504429734236;8334.416590838584;8354.373421993374;8387.287080598218;6096.481799047131;6152.569320147547;6196.225471816023;6222.968885155546;6305.243370795534;10192;10234.948825255826;10332.225083118947;10333.379997200193;10351.896481035094;9998.950005250219;10272.435016074187;10529.10502607287;10564.894351056699;10602.469876506299;7697.114831794415;7759.573223472763;7819.569923654026;7834.294913457816;7840.3335716464735;7968.601569921365;8312.25189732927;8316.085230624747;8437.198466145706;8462;7314.853702925805;7534.434917381109;7599.088109426904;7622.31399174077;7661.387089032753;7408.888866667112;7421.257874212535;7485.812854678677;7544.47188696786;7546.3962882968135;7029.824254393681;7146.464015198777;7214.494985350975;7219;7219.8556028880175;4985.202367621163;5057.873553161053;5071.619628527801;5124.1032819257225;5220.21696745476;4914.0908931847725;5084.694918304914;5145.9228111579105;5156.587473002226;5299.814506492255 16:29:10 INFO - PROCESS | 1647 | |4;prototype.html;285.3932360808647;304.85367023828564;305.4883070856315;315.1522404731272;318.20448877805484;5101.413337466192;5225.921611175831;5340.706261155635;5385.757640906154;5417.778710728602;4331.740095594267;5189.974050129754;5330.173823057423;5331.466853314667;5356.866078348034;3507.9298414031705;3729.9813500932455;3886.31989401855;3956.9423899572207;4063.8577649782264;3846.615338466159;3928.626780455852;4110.362893751473;4111.787022828273;4132.793360331986;4049.3521036634143;4054.939175912349;4066.593340665939;4074.144429669758;4076.8788583139576;3347.6484969078383;3852.495877679503;4083.571225021389;4086.7139300248996;4089.591040895916;3446.0357596170193;3544.8227588620466;3576.6423357664285;3585.928281434357;3588.2644057968055;5193.37679478465;5200.323957885488;5227.79088836446;5240.633155679104;5242.213667949838;3664.5236119304586;4638.605718513931;4652.976735116303;4653.581177694024;4674.976625116887;4837.806487740486;5414.187871819219;5537.58468114893;5605.943940560624;5631.436856314377;3423.4252243967803;3744.8314825832904;3911.1395492991496;3933.1347103637154;3943.245255861143;4832.565069143794;5895.233619629421;5908.804748628436;5912.822615321547;5916.201312822757;3815.4467602197524;3997.6801855851463;4014.8996275092773;4019.834248068086;4042.4138499917935;2994.9517668816175;3329.583802024747;3853.8265778040054;3998.7800670963375;4076.3477843544892;4434.84478043267;4566.3150527420485;4575.542445755398;4589.380433641482;4606.654500912445;5452.754626041838;5573.749181286852;5617.522510586564;5692.800751973661;5712.542996560265;1388.3617331548667;1410.7813288940026;1411.3578321863527;1415.348939487847;1415.5965549818252;797.8962734844433;867.5011868175698;922.6217250927087;926.1988380051342;926.6200857648332;4851.320815085891;5120.334356533627;5217.556507696889;5335.653182543122;5358.490943360374;4952.331435256267;5789.652620842763;5833.766649333979;5847.736851841677;5853.9414605854245;4149.647279981175;4590.380298659638;5426.972865135649;5447.105319696436;5456.454354564512;2798.5522316429274;2933.965797029085;3046.3450358172654;3070.5282247790296;3083.2908431060982;5806.68063256525;6052.182955300978;6065;6075.908861367083;6084.756609735649;5552.639078459887;5556.361018482926;5606.028804733498;5994.820155395345;6027.432867454493;5084.07665592286;5278.305186369556;6003.7898673546215;6028.728707208186;6041.190846256512;4858.489858564941;5328.6944698549105;5473.945260547343;5550.307156317362;5838.985549985248;5011.774470148925;5486.204500347507;5733.971330143323;5741.282339707536;5757.625754325955;4950.975245123751;5552.222688823487;5699.715014249188;6034.788782392595;6049.909251361321;3355.5134505497053;3873.593272706325;3891.8248678810087;3915.823787929493;3919.510061242345;4740.525947405232;5697.658140511582;5706.061378390049;5837.824865254049;5844.795432160024;4952.4057113146655;5671.773129074791;5687.687177205294;5690.374058853606;5697.401772813795;4644.674872758875;4938.38270216223;5648;5663.745131469176;5677.3471050829685;4655.627549796076;5521.171824226398;5618.628822706343;5661.490465857928;5663.858403539944;5682.971585142048;5705.600607957569;5754.992876246556;5772.7690892363835;5793.3337666167245;4672.626189904868;4807.6394270429155;5342.839714808562;5644.322681278273;5664.773409063756 16:29:10 INFO - PROCESS | 1647 | |5;yui.html;622.6855438003809;730.6154836584669;806.4596720197471;811.6388207247774;845.8181490979438;14828.77756833647;16599.502014939557;16601.33594656212;16628.75056874145;16655.999999999985;15072.547823565283;15206.23968801561;15249.161296128708;15268.618284542892;15825.920870395632;893.9374243802953;909.6406919266885;922.0042354257386;922.423485321674;922.9538523073819;701.1726163127507;732.9816754581125;906.9727908162765;909.1317791509126;910.0717268386242;770.1912991359079;771.533222400448;771.9189485104067;918;967.7435479597913;950.0357137505438;950.6577632052456;951.3435729346763;952.1525842000625;954.5036580977853;702.0276916470725;702.178451212078;702.3082263969985;702.7540360873704;703.3985942019551;12701.174423662433;13781.724365512633;15645.921770391189;15658.982166159278;15686.607834804106;12949.287789171593;14339.78490322646;14409.06341087826;14682.926585367111;14788.848113052498;11743.47154378055;11948.880511194951;12058.819117713241;12062;12447.688807779787;3852.7303088783797;3856.67218286446;3858.112634094146;3884.961150388488;4032.3145065338963;95848;99131.0086899129;114006;115832.42083789612;117733;11283.266587671775;11525.654230373102;11569.768604628029;11661.18371713989;11670.416479176007;22589.548209036057;22602;22611.095556177897;22653.546929061325;22666.093356265894;8271.33829293655;8280.58597070144;8327.625256863335;8476.830463390697;8551.786205344915;4425.380446737459;4524.411826462539;4678.157931572282;4686.859394218179;4756.738379389098;3555.520004799371;3694.5935947045805;3710.461983012448;3711.3690672585726;3718.200586873835;567.9687617181095;688.7176257734405;696.1611258433677;696.1646024770296;696.2619623199323;532.6777299733683;570.922925405065;573.7217449536971;679.2087218390519;687.656171914043;8589.785255368666;8952.060033696498;9252.629894804268;9442.858357124649;9600.567974441166;660.9173853268342;661.2626920983165;675.9864802703919;684.3327755438407;706.7738323736457;4676.158291507493;4680.765961701969;4725.929111063337;4736.644751643572;4792.904141917142;156502;157616;157721.2113939446;157894;159167;98929.50535247277;134290;134536.32731836278;136351.31824341012;139622.30188848992;112854;118569;119597;121226;121423;9678.70963871085;9776.21790256792;9788.951055244679;9862.117783117166;10178.6437474691;61154;62860;62888.68555657192;65232.69534609376;65663;98812;99662;100259.49870250603;100509;101153.49423252836;11725.941370293094;13487.393067311797;13491.325433728078;14036;14152.78770818459;15009.849901500846;15607.921960390126;15613.84386156124;15682.607934801721;16173.4339298124;14154.363053662404;14303.499377521737;14451.349689263798;14456.85543144555;14924.85075149278;13879.306034698022;14320.856791431952;14467.565973020404;14784.704305914036;14975.550733478012;11949.402529873645;13812.723745524832;14002;14116;14438.494652687106;10943.781124377627;11609.245399049034;11775.587854425397;11846.703832404259;11912.3601332347;9354.95322523383;11528.82706759416;11625.18623696333;11648.126390520576;11782.03240058917 16:29:10 INFO - PROCESS | 1647 | __end_tp_report 16:29:10 INFO - PROCESS | 1647 | __start_cc_report 16:29:10 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,2170 16:29:10 INFO - PROCESS | 1647 | __end_cc_report 16:29:10 INFO - PROCESS | 1647 | __startTimestamp1468970950494__endTimestamp 16:29:10 INFO - PROCESS | 1647 | 16:29:10 INFO - PROCESS | 1647 | ------- Summary: start ------- 16:29:10 INFO - PROCESS | 1647 | Number of tests: 6 16:29:10 INFO - PROCESS | 1647 | 16:29:10 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5653.36 Median:5812.24 stddev:637.15 (11.0%) stddev-sans-first:611.68 16:29:10 INFO - PROCESS | 1647 | Values: 3296.0 3305.2 3329.4 3341.4 3387.1 5504.9 5532.0 5567.2 5585.7 5603.0 5601.0 5640.0 5642.8 5644.4 5645.2 5094.9 5137.6 5162.6 5168.8 5224.5 5183.9 5193.2 5197.5 5200.5 5215.4 5077.4 5098.0 5106.6 5110.9 5188.4 5156.5 5173.8 5183.9 5191.6 5242.5 5002.2 5028.1 5030.3 5113.9 5141.5 5516.6 5517.8 5569.8 5575.9 5576.8 6153.5 6158.6 6163.9 6164.0 6171.0 5587.1 6114.0 6191.8 6198.9 6209.8 5714.1 5720.9 5722.9 5745.1 5754.7 6300.6 6324.7 6345.5 6349.5 6354.8 5873.2 5937.0 5945.5 6021.8 6084.9 6178.4 6199.9 6200.7 6209.3 6217.3 6123.0 6128.6 6133.5 6138.5 6150.0 5960.1 5989.3 6162.3 6166.0 6189.1 4799.1 4832.4 4872.5 4872.9 4881.8 4353.3 4359.4 4374.6 4396.8 4397.2 5469.7 5507.3 5526.6 5622.2 5635.2 6130.8 6173.0 6207.8 6210.7 6220.6 5551.2 5567.1 5568.5 5570.8 5595.5 5195.4 5222.9 5318.7 5320.3 5334.5 6266.2 6319.6 6323.1 6328.3 6332.5 6206.9 6240.9 6246.9 6249.0 6257.9 6219.2 6261.8 6301.7 6306.3 6354.1 6051.8 6090.4 6091.2 6095.9 6097.2 6169.6 6194.3 6206.7 6208.0 6228.2 5762.8 5772.0 5813.6 5828.6 5966.8 5865.5 5871.8 5873.1 5875.5 5877.7 5778.7 5812.2 5872.1 5876.9 5881.4 5817.2 6009.2 6017.9 6035.2 6053.2 4837.7 5638.5 5757.4 5825.4 5886.3 16:29:10 INFO - PROCESS | 1647 | 16:29:10 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:64859.82 Median:21240.44 stddev:132497.93 (623.8%) stddev-sans-first:132781.59 16:29:10 INFO - PROCESS | 1647 | Values: 531.6 531.9 537.9 558.0 563.3 26879.6 41777.4 42652.6 42994.1 45455.5 44588.1 44807.0 45149.8 45159.3 45176.5 8854.9 9876.6 10014.0 10034.0 10044.5 8823.1 9055.0 9059.3 9061.9 11881.5 12085.1 12394.0 12479.4 12481.4 12482.3 12190.4 12269.8 12270.4 12281.3 12288.4 8725.7 11931.0 12030.6 12154.0 12175.8 42666.8 43082.6 43093.0 43193.8 43228.4 3568.6 3882.8 4145.7 4225.1 4268.1 20744.4 25881.9 29204.9 33144.5 33555.2 3233.2 3236.2 3280.0 3310.8 3355.1 409140.0 409718.0 410869.0 412496.0 412506.0 62005.1 62922.7 62929.4 63146.0 63165.7 65304.7 65319.7 65497.0 65581.0 65622.7 16075.8 16833.2 16845.3 16859.9 16892.3 13033.1 13098.2 13112.8 13120.7 13139.6 14952.0 15157.4 16652.8 16781.7 16823.6 6383.0 7524.6 7573.3 7593.5 7671.3 922.0 1015.3 1024.1 1073.3 1148.4 25236.4 25358.4 28672.6 30366.0 31305.7 1061.1 1107.7 1117.1 1118.6 1222.9 15904.8 16417.0 16751.0 16983.8 17398.6 563457.0 571252.0 574499.0 576015.0 577438.0 62017.7 62309.0 62321.0 62343.4 62644.7 1306.7 1334.4 1423.7 1447.4 1481.2 30536.1 31795.7 32053.6 32481.5 32538.3 51439.5 51886.7 51955.0 51977.0 52023.0 498084.5 498666.0 498745.0 498759.0 499012.0 24224.0 24670.4 26025.9 26842.9 27289.9 38860.6 49811.0 49873.3 52787.2 53884.0 40164.0 41753.6 42130.0 42589.0 43033.8 31846.8 40666.8 40998.8 41905.0 41931.8 38598.0 39069.0 39359.2 39565.0 39896.8 33659.8 34415.1 35221.8 36318.0 36390.6 25872.5 26146.8 26260.6 26626.0 26677.3 16:29:10 INFO - PROCESS | 1647 | 16:29:10 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:37840.83 Median:2466.53 stddev:102364.46 (4150.1%) stddev-sans-first:102612.93 16:29:10 INFO - PROCESS | 1647 | Values: 358.0 379.2 383.0 385.5 404.6 15351.4 15435.5 20891.9 20952.4 21235.8 15758.4 20666.9 21102.7 21166.9 21640.0 5460.8 6019.9 6230.7 6237.1 6248.3 6150.8 6183.1 6224.5 6225.9 6226.7 5960.5 6054.3 6132.7 6154.6 6178.9 6007.3 6115.6 6121.4 6125.7 6134.8 5159.5 5258.9 5260.9 5288.9 5298.9 25511.5 26195.7 26443.3 26470.5 28895.7 2440.9 2492.1 2577.5 2605.5 2652.5 17247.4 17402.7 17578.4 17823.2 17988.6 1817.8 1994.3 2002.4 2016.1 2111.0 119493.0 142455.0 150420.0 153045.0 153440.2 21098.4 22409.8 22442.3 22551.0 22651.4 36441.6 36442.6 36458.6 36527.8 36535.6 10846.1 11337.6 11348.4 11358.7 11538.6 9079.4 9097.2 9130.9 9244.3 9289.7 9997.9 10039.2 10047.6 10174.4 10175.6 3112.6 3830.7 3873.4 3974.8 4044.3 715.9 794.2 817.3 927.9 959.9 20215.5 21165.6 21468.8 21563.5 21569.4 904.2 974.2 1017.5 1021.9 1024.2 11013.6 11302.3 11313.8 11330.1 11335.4 598030.0 605763.0 612201.0 616265.0 617598.0 108464.9 138320.0 140669.0 141260.0 143735.0 1017.1 1018.4 1072.5 1097.0 1097.8 18074.8 18422.2 18457.1 18597.4 18988.5 44064.8 51469.7 53971.0 56653.7 57605.0 78087.6 78313.0 84756.6 85151.0 85579.7 7060.5 8366.2 8444.5 8490.0 8500.1 9560.3 13206.9 13334.1 13355.3 13439.9 11446.1 11448.7 11507.2 11507.4 11603.5 11231.9 11367.2 11398.4 11467.7 11506.1 9313.3 11499.1 11600.3 11764.5 11770.4 7057.5 8254.9 8357.4 8406.5 8445.8 10690.9 11273.7 11507.8 11553.5 11581.9 16:29:10 INFO - PROCESS | 1647 | 16:29:10 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5815.31 Median:4696.04 stddev:3303.96 (70.4%) stddev-sans-first:3297.69 16:29:10 INFO - PROCESS | 1647 | Values: 1552.0 1599.1 1823.1 1953.9 1974.0 8635.7 8979.0 9135.9 9136.4 9140.5 7896.4 8113.3 8595.9 8857.6 8931.2 2333.5 2360.4 2392.9 2403.2 2403.9 2362.0 2370.1 2407.9 2409.2 2415.2 2082.1 2156.6 2159.1 2159.8 2162.8 2315.8 2384.5 2386.9 2397.1 2398.1 2366.3 2367.6 2375.6 2389.1 2392.6 7900.7 8707.6 8936.0 9065.0 9071.9 5170.5 5376.3 5383.7 5384.7 5394.7 3551.4 3579.4 3620.3 3623.9 3645.3 1069.4 1070.7 1083.1 1086.8 1089.1 12031.6 12114.2 12118.6 12123.2 12157.9 4407.4 4669.0 4861.1 4877.2 4882.7 10356.6 10372.4 10380.1 10380.7 10391.9 5432.7 5437.8 5438.3 5441.4 5458.3 4914.6 4934.8 4966.2 4971.1 4980.2 3866.9 3880.7 3923.6 3936.9 3977.8 1254.9 1270.1 1273.7 1279.2 1285.6 930.6 936.6 942.5 963.8 982.7 4723.1 6079.1 6196.0 6199.5 6237.4 445.6 456.8 464.1 464.5 466.3 4048.0 4059.4 4108.5 4146.7 4172.5 10595.3 10641.0 10993.5 11011.4 11019.4 10934.9 10945.5 10954.2 10963.8 10975.5 7965.3 8259.5 8334.4 8354.4 8387.3 6096.5 6152.6 6196.2 6223.0 6305.2 10192.0 10234.9 10332.2 10333.4 10351.9 9999.0 10272.4 10529.1 10564.9 10602.5 7697.1 7759.6 7819.6 7834.3 7840.3 7968.6 8312.3 8316.1 8437.2 8462.0 7314.9 7534.4 7599.1 7622.3 7661.4 7408.9 7421.3 7485.8 7544.5 7546.4 7029.8 7146.5 7214.5 7219.0 7219.9 4985.2 5057.9 5071.6 5124.1 5220.2 4914.1 5084.7 5145.9 5156.6 5299.8 16:29:10 INFO - PROCESS | 1647 | 16:29:10 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4561.85 Median:5191.68 stddev:1405.45 (27.1%) stddev-sans-first:1372.25 16:29:10 INFO - PROCESS | 1647 | Values: 285.4 304.9 305.5 315.2 318.2 5101.4 5225.9 5340.7 5385.8 5417.8 4331.7 5190.0 5330.2 5331.5 5356.9 3507.9 3730.0 3886.3 3956.9 4063.9 3846.6 3928.6 4110.4 4111.8 4132.8 4049.4 4054.9 4066.6 4074.1 4076.9 3347.6 3852.5 4083.6 4086.7 4089.6 3446.0 3544.8 3576.6 3585.9 3588.3 5193.4 5200.3 5227.8 5240.6 5242.2 3664.5 4638.6 4653.0 4653.6 4675.0 4837.8 5414.2 5537.6 5605.9 5631.4 3423.4 3744.8 3911.1 3933.1 3943.2 4832.6 5895.2 5908.8 5912.8 5916.2 3815.4 3997.7 4014.9 4019.8 4042.4 2995.0 3329.6 3853.8 3998.8 4076.3 4434.8 4566.3 4575.5 4589.4 4606.7 5452.8 5573.7 5617.5 5692.8 5712.5 1388.4 1410.8 1411.4 1415.3 1415.6 797.9 867.5 922.6 926.2 926.6 4851.3 5120.3 5217.6 5335.7 5358.5 4952.3 5789.7 5833.8 5847.7 5853.9 4149.6 4590.4 5427.0 5447.1 5456.5 2798.6 2934.0 3046.3 3070.5 3083.3 5806.7 6052.2 6065.0 6075.9 6084.8 5552.6 5556.4 5606.0 5994.8 6027.4 5084.1 5278.3 6003.8 6028.7 6041.2 4858.5 5328.7 5473.9 5550.3 5839.0 5011.8 5486.2 5734.0 5741.3 5757.6 4951.0 5552.2 5699.7 6034.8 6049.9 3355.5 3873.6 3891.8 3915.8 3919.5 4740.5 5697.7 5706.1 5837.8 5844.8 4952.4 5671.8 5687.7 5690.4 5697.4 4644.7 4938.4 5648.0 5663.7 5677.3 4655.6 5521.2 5618.6 5661.5 5663.9 5683.0 5705.6 5755.0 5772.8 5793.3 4672.6 4807.6 5342.8 5644.3 5664.8 16:29:10 INFO - PROCESS | 1647 | 16:29:10 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:25828.62 Median:3625.06 stddev:41290.84 (1139.0%) stddev-sans-first:41363.30 16:29:10 INFO - PROCESS | 1647 | Values: 622.7 730.6 806.5 811.6 845.8 14828.8 16599.5 16601.3 16628.8 16656.0 15072.5 15206.2 15249.2 15268.6 15825.9 893.9 909.6 922.0 922.4 923.0 701.2 733.0 907.0 909.1 910.1 770.2 771.5 771.9 918.0 967.7 950.0 950.7 951.3 952.2 954.5 702.0 702.2 702.3 702.8 703.4 12701.2 13781.7 15645.9 15659.0 15686.6 12949.3 14339.8 14409.1 14682.9 14788.8 11743.5 11948.9 12058.8 12062.0 12447.7 3852.7 3856.7 3858.1 3885.0 4032.3 95848.0 99131.0 114006.0 115832.4 117733.0 11283.3 11525.7 11569.8 11661.2 11670.4 22589.5 22602.0 22611.1 22653.5 22666.1 8271.3 8280.6 8327.6 8476.8 8551.8 4425.4 4524.4 4678.2 4686.9 4756.7 3555.5 3694.6 3710.5 3711.4 3718.2 568.0 688.7 696.2 696.2 696.3 532.7 570.9 573.7 679.2 687.7 8589.8 8952.1 9252.6 9442.9 9600.6 660.9 661.3 676.0 684.3 706.8 4676.2 4680.8 4725.9 4736.6 4792.9 156502.0 157616.0 157721.2 157894.0 159167.0 98929.5 134290.0 134536.3 136351.3 139622.3 112854.0 118569.0 119597.0 121226.0 121423.0 9678.7 9776.2 9789.0 9862.1 10178.6 61154.0 62860.0 62888.7 65232.7 65663.0 98812.0 99662.0 100259.5 100509.0 101153.5 11725.9 13487.4 13491.3 14036.0 14152.8 15009.8 15607.9 15613.8 15682.6 16173.4 14154.4 14303.5 14451.3 14456.9 14924.9 13879.3 14320.9 14467.6 14784.7 14975.6 11949.4 13812.7 14002.0 14116.0 14438.5 10943.8 11609.2 11775.6 11846.7 11912.4 9355.0 11528.8 11625.2 11648.1 11782.0 16:29:10 INFO - PROCESS | 1647 | -------- Summary: end -------- 16:29:10 INFO - PROCESS | 1647 | 16:29:11 INFO - TEST-INFO | 1647: exit 0 16:29:11 INFO - TEST-OK | dromaeo_css | took 1116444ms 16:29:11 INFO - TEST-START | kraken 16:29:11 INFO - operating with platform_type : mac_ 16:29:11 INFO - Initialising browser for kraken test... 16:29:11 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmp5EkEDO/profile http://localhost:49272/getInfo.html) 16:29:13 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 16:29:13 INFO - PROCESS | 1656 | colorDepth:24 16:29:13 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 16:29:13 INFO - PROCESS | 1656 | __metrics 16:29:13 INFO - PROCESS | 1656 | 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" 16:29:13 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:29:14 INFO - TEST-INFO | 1656: exit 0 16:29:14 INFO - Browser initialized. 16:29:14 INFO - Running cycle 1/1 for kraken test... 16:29:14 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmp5EkEDO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:29:26 INFO - PROCESS | 1657 | RSS: Main: 245182464 16:29:26 INFO - PROCESS | 1657 | 16:29:28 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 16:29:29 INFO - PROCESS | 1657 | RSS: Main: 293941248 16:29:29 INFO - PROCESS | 1657 | 16:29:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 16:29:32 INFO - PROCESS | 1657 | RSS: Main: 304709632 16:29:32 INFO - PROCESS | 1657 | 16:29:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 16:29:36 INFO - PROCESS | 1657 | RSS: Main: 387420160 16:29:36 INFO - PROCESS | 1657 | 16:29:38 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 16:29:39 INFO - PROCESS | 1657 | RSS: Main: 292044800 16:29:39 INFO - PROCESS | 1657 | 16:29:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:29:42 INFO - PROCESS | 1657 | RSS: Main: 295518208 16:29:42 INFO - PROCESS | 1657 | 16:29:44 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 16:29:45 INFO - PROCESS | 1657 | RSS: Main: 332095488 16:29:45 INFO - PROCESS | 1657 | 16:29:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 16:29:49 INFO - PROCESS | 1657 | RSS: Main: 333238272 16:29:49 INFO - PROCESS | 1657 | 16:29:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 16:29:52 INFO - PROCESS | 1657 | RSS: Main: 333971456 16:29:52 INFO - PROCESS | 1657 | 16:29:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:29:54 INFO - PROCESS | 1657 | RSS: Main: 311373824 16:29:54 INFO - PROCESS | 1657 | 16:29:56 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:29:57 INFO - PROCESS | 1657 | RSS: Main: 318091264 16:29:57 INFO - PROCESS | 1657 | 16:29:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:30:00 INFO - PROCESS | 1657 | RSS: Main: 316313600 16:30:00 INFO - PROCESS | 1657 | 16:30:02 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:30:03 INFO - PROCESS | 1657 | RSS: Main: 324820992 16:30:03 INFO - PROCESS | 1657 | 16:30:06 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:30:07 INFO - PROCESS | 1657 | RSS: Main: 326258688 16:30:07 INFO - PROCESS | 1657 | 16:30:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 16:30:09 INFO - PROCESS | 1657 | RSS: Main: 369807360 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | __start_tp_report 16:30:09 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 16:30:09 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 16:30:09 INFO - PROCESS | 1657 | |i|pagename|runs| 16:30:09 INFO - PROCESS | 1657 | |0;ai-astar;103;112;114;115;114;112;113;112;113;114 16:30:09 INFO - PROCESS | 1657 | |1;audio-beat-detection;143;106;107;158;125;136;128;107;163;109 16:30:09 INFO - PROCESS | 1657 | |2;audio-dft;185;187;184;185;184;184;180;183;185;175 16:30:09 INFO - PROCESS | 1657 | |3;audio-fft;79;74;58;87;59;57;61;67;82;56 16:30:09 INFO - PROCESS | 1657 | |4;audio-oscillator;83;83;91;72;69;68;96;69;68;97 16:30:09 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;87;85;88;85;94;130;88;85;87;84 16:30:09 INFO - PROCESS | 1657 | |6;imaging-darkroom;94;94;93;96;93;93;95;94;94;94 16:30:09 INFO - PROCESS | 1657 | |7;imaging-desaturate;87;93;136;86;84;85;87;84;87;89 16:30:09 INFO - PROCESS | 1657 | |8;json-parse-financial;57;55;55;51;55;53;55;53;53;53 16:30:09 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;67;66;76;76;72;70;70;70;70;72 16:30:09 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;82;82;85;83;91;100;79;82;84;83 16:30:09 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;145;151;152;130;142;141;127;117;188;142 16:30:09 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;170;170;168;168;171;169;167;169;173;172 16:30:09 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;68;67;70;83;88;72;68;67;70 16:30:09 INFO - PROCESS | 1657 | __end_tp_report 16:30:09 INFO - PROCESS | 1657 | __start_cc_report 16:30:09 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2073 16:30:09 INFO - PROCESS | 1657 | __end_cc_report 16:30:09 INFO - PROCESS | 1657 | __startTimestamp1468971009086__endTimestamp 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | ------- Summary: start ------- 16:30:09 INFO - PROCESS | 1657 | Number of tests: 14 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.50 stddev:3.39 (3.0%) stddev-sans-first:1.09 16:30:09 INFO - PROCESS | 1657 | Values: 103.0 112.0 114.0 115.0 114.0 112.0 113.0 112.0 113.0 114.0 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:132.00 stddev:21.47 (16.3%) stddev-sans-first:22.10 16:30:09 INFO - PROCESS | 1657 | Values: 143.0 106.0 107.0 158.0 125.0 136.0 128.0 107.0 163.0 109.0 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.50 stddev:3.39 (1.8%) stddev-sans-first:3.54 16:30:09 INFO - PROCESS | 1657 | Values: 185.0 187.0 184.0 185.0 184.0 184.0 180.0 183.0 185.0 175.0 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:68.00 Median:70.50 stddev:11.60 (16.4%) stddev-sans-first:11.60 16:30:09 INFO - PROCESS | 1657 | Values: 79.0 74.0 58.0 87.0 59.0 57.0 61.0 67.0 82.0 56.0 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:79.60 Median:83.00 stddev:11.91 (14.3%) stddev-sans-first:12.57 16:30:09 INFO - PROCESS | 1657 | Values: 83.0 83.0 91.0 72.0 69.0 68.0 96.0 69.0 68.0 97.0 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:87.00 stddev:13.89 (16.0%) stddev-sans-first:14.64 16:30:09 INFO - PROCESS | 1657 | Values: 87.0 85.0 88.0 85.0 94.0 130.0 88.0 85.0 87.0 84.0 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.94 (1.0%) stddev-sans-first:1.00 16:30:09 INFO - PROCESS | 1657 | Values: 94.0 94.0 93.0 96.0 93.0 93.0 95.0 94.0 94.0 94.0 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:91.80 Median:87.00 stddev:15.75 (18.1%) stddev-sans-first:16.61 16:30:09 INFO - PROCESS | 1657 | Values: 87.0 93.0 136.0 86.0 84.0 85.0 87.0 84.0 87.0 89.0 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:55.00 stddev:1.70 (3.1%) stddev-sans-first:1.41 16:30:09 INFO - PROCESS | 1657 | Values: 57.0 55.0 55.0 51.0 55.0 53.0 55.0 53.0 53.0 53.0 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:3.28 (4.6%) stddev-sans-first:3.16 16:30:09 INFO - PROCESS | 1657 | Values: 67.0 66.0 76.0 76.0 72.0 70.0 70.0 70.0 70.0 72.0 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:83.00 stddev:6.08 (7.3%) stddev-sans-first:6.35 16:30:09 INFO - PROCESS | 1657 | Values: 82.0 82.0 85.0 83.0 91.0 100.0 79.0 82.0 84.0 83.0 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:143.50 Median:143.50 stddev:19.09 (13.3%) stddev-sans-first:20.24 16:30:09 INFO - PROCESS | 1657 | Values: 145.0 151.0 152.0 130.0 142.0 141.0 127.0 117.0 188.0 142.0 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:170.00 stddev:1.89 (1.1%) stddev-sans-first:2.00 16:30:09 INFO - PROCESS | 1657 | Values: 170.0 170.0 168.0 168.0 171.0 169.0 167.0 169.0 173.0 172.0 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.00 Median:70.00 stddev:7.39 (10.6%) stddev-sans-first:7.62 16:30:09 INFO - PROCESS | 1657 | Values: 67.0 68.0 67.0 70.0 83.0 88.0 72.0 68.0 67.0 70.0 16:30:09 INFO - PROCESS | 1657 | -------- Summary: end -------- 16:30:09 INFO - PROCESS | 1657 | 16:30:09 INFO - TEST-INFO | 1657: exit 0 16:30:09 INFO - TEST-OK | kraken | took 58875ms 16:30:09 INFO - SUITE-END | took 1175s 16:30:10 INFO - Completed test suite (00:19:35) 16:30:10 INFO - javascript benchmark 16:30:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3296.0441471973113, 3305.206750379909, 3329.4339962206423, 3341.4152523308417, 3387.136280248538, 5504.9174262386, 5532.004239236936, 5567.164925261213, 5585.664860108392, 5603.0, 5601.019821531236, 5639.971800141004, 5642.7742890284335, 5644.435556444353, 5645.1814486899375, 5094.898102037966, 5137.614678899097, 5162.638615296931, 5168.7932482700835, 5224.4775522447835, 5183.870403239912, 5193.221016847466, 5197.506236907488, 5200.505951934579, 5215.374155101393, 5077.365329333833, 5098.031374038921, 5106.64253502255, 5110.872228194306, 5188.377394712621, 5156.53591176794, 5173.844784656466, 5183.870403239949, 5191.636585439021, 5242.475752424727, 5002.199648056294, 5028.094942910275, 5030.346055012825, 5113.948860511385, 5141.460146684619, 5516.558675305966, 5517.779288828442, 5569.805056823032, 5575.8606034849045, 5576.804811831566, 6153.507719382484, 6158.56890017699, 6163.9383606164265, 6163.96918015407, 6170.96914515429, 5587.106063029895, 6114.021756518936, 6191.845203869894, 6198.938010619881, 6209.751609935597, 5714.057180565202, 5720.942790572041, 5722.942770572325, 5745.080787074048, 5754.6547207167705, 6300.558960872695, 6324.683765811691, 6345.524085693499, 6349.52378571609, 6354.809355719336, 5873.20711703923, 5936.970315148396, 5945.494632956247, 6021.759129634853, 6084.8783024340155, 6178.382161783786, 6199.938000620026, 6200.689965501707, 6209.285932117774, 6217.316095229521, 6123.020316749299, 6128.571000029955, 6133.509319254449, 6138.508919286535, 6149.96925015372, 5960.135780311831, 5989.281286245591, 6162.26052873649, 6166.0, 6189.133521307021, 4799.064182484382, 4832.371791667061, 4872.4883887192045, 4872.902541949212, 4881.804727810919, 4353.325234588644, 4359.433273674465, 4374.606285434326, 4396.846110386121, 4397.2304846651705, 5469.699166545878, 5507.256520369779, 5526.613137080446, 5622.212890195376, 5635.1828984796975, 6130.795626845359, 6172.969135154385, 6207.751689932352, 6210.72051757672, 6220.595661282002, 5551.195076713854, 5567.081431563746, 5568.470995255444, 5570.805021824217, 5595.468430499096, 5195.402528709247, 5222.947770522323, 5318.734063296897, 5320.255164277004, 5334.51989320963, 6266.154069200599, 6319.6208227506495, 6323.051542268697, 6328.303886572381, 6332.461740752087, 6206.944819380807, 6240.875182496416, 6246.875062498633, 6249.000159974382, 6257.906131408124, 6219.191505104308, 6261.78083767066, 6301.716422761078, 6306.337834527309, 6354.142190804182, 6051.848703782441, 6090.360512146338, 6091.238595175603, 6095.908561371672, 6097.237845269341, 6169.607058928683, 6194.318624951162, 6206.72069756853, 6208.0, 6228.190335256388, 5762.7694892203845, 5771.98990176709, 5813.6221145625395, 5828.59199856006, 5966.791162309299, 5865.472107510344, 5871.7651293947765, 5873.060310350324, 5875.471207591338, 5877.676727779842, 5778.653280803165, 5812.244408226903, 5872.119182122716, 5876.94123058781, 5881.38245484218, 5817.185594016925, 6009.248843894513, 6017.909731354121, 6035.155078288955, 6053.213082299273, 4837.733924634179, 5638.4925356718095, 5757.424257574209, 5825.3592104867585, 5886.293644762656], "value": 5612.136187669615, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [531.5694287627023, 531.9387821296514, 537.9354477462705, 557.9816834277444, 563.2818156850016, 26879.596806047924, 41777.373339399936, 42652.573474265206, 42994.14011719764, 45455.54544454555, 44588.1082378353, 44807.0, 45149.77425112878, 45159.322610160874, 45176.54823451764, 8854.86717699235, 9876.555555000024, 10014.0, 10034.0, 10044.497775111251, 8823.11768823119, 9055.0, 9059.320550958671, 9061.864072038892, 11881.524739010429, 12085.093617978642, 12394.008479321677, 12479.37603119845, 12481.375931203449, 12482.313472758995, 12190.390480475986, 12269.754604907852, 12270.38648067593, 12281.263124212532, 12288.447019884126, 8725.650973961034, 11931.04551635867, 12030.63908082759, 12154.027677785756, 12175.756484870342, 42666.78666606647, 43082.56917430817, 43093.0, 43193.78403107996, 43228.3515747264, 3568.6431356864105, 3882.839537435628, 4145.689073319513, 4225.112726327444, 4268.146370725867, 20744.377668669964, 25881.870590646926, 29204.8539757302, 33144.50849711769, 33555.161120972174, 3233.2401885556874, 3236.2394837213237, 3280.0487858521, 3310.8344582770765, 3355.0605830367535, 409140.0, 409718.0, 410869.0, 412496.0, 412506.0, 62005.069923951174, 62922.685386572775, 62929.370706293266, 63146.0, 63165.68417157885, 65304.67347663231, 65319.67340163269, 65497.01754473687, 65581.0, 65622.67188664027, 16075.758863617055, 16833.242504087346, 16845.326186952385, 16859.915700421418, 16892.324307027582, 13033.087683862228, 13098.2141071536, 13112.803307950388, 13120.671983200305, 13139.60581182566, 14951.953363264467, 15157.393704251926, 16652.75020874688, 16781.748273775902, 16823.57941051459, 6383.0, 7524.629741631368, 7573.2805383488485, 7593.506422082547, 7671.347935425551, 921.9861702074475, 1015.2740790334946, 1024.08856118055, 1073.313079629038, 1148.4487446025955, 25236.369090772878, 25358.366040849127, 28672.569911451345, 30366.0, 31305.68694313073, 1061.0874647802884, 1107.7482444837283, 1117.0951529261324, 1118.6196693124377, 1222.9266244025387, 15904.840951590337, 16417.0, 16751.0, 16983.830161698224, 17398.565035874206, 563457.0, 571252.0, 574499.0, 576015.0, 577438.0, 62017.68991155015, 62309.0, 62321.0, 62343.376566233754, 62644.686776566734, 1306.7125232448846, 1334.3861823561076, 1423.7223741370747, 1447.4282658349894, 1481.2445652716976, 30536.083917482512, 31795.682043179273, 32053.557589908978, 32481.51277730883, 32538.349233014735, 51439.48560514347, 51886.740566296925, 51955.0, 51977.0, 52023.0, 498084.5095774498, 498666.0, 498745.0, 498759.0, 499012.0, 24224.0, 24670.383240419134, 26025.869870650524, 26842.86578567173, 27289.863550682912, 38860.6113938857, 49811.003779924926, 49873.25190122223, 52787.20819187792, 53884.0, 40164.0, 41753.58246417619, 42130.0, 42589.0, 43033.784831075645, 31846.840765796023, 40666.79666601648, 40998.795006025975, 41905.0, 41931.790341049316, 38598.035049123995, 39069.0, 39359.212815742954, 39565.0, 39896.80051599723, 33659.83170084232, 34415.13962150966, 35221.767238146545, 36318.0, 36390.63609363873, 25872.48255034851, 26146.823392947743, 26260.60609090827, 26626.0, 26677.333066672712], "value": 20584.689608288452, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.98747043853473, 379.22637818849546, 382.9770213787171, 385.4911516797827, 404.60348858119045, 15351.385944562218, 15435.536933891986, 20891.895540522313, 20952.37142885712, 21235.787642123574, 15758.448454304102, 20666.89666551665, 21102.683459748117, 21166.89416552919, 21640.02619882105, 5460.836174914739, 6019.909701354483, 6230.657313847737, 6237.064440333941, 6248.250209974809, 6150.753969841201, 6183.072539119124, 6224.470919971808, 6225.875482490347, 6226.657533835637, 5960.493358064569, 6054.273487181545, 6132.693365331738, 6154.599951003171, 6178.876422471548, 6007.279126504804, 6115.633062016271, 6121.449069583737, 6125.663088530129, 6134.8159555213415, 5159.484051594848, 5258.947410525884, 5260.92108618371, 5288.867778305535, 5298.947010529884, 25511.48977020449, 26195.73804261952, 26443.33891652705, 26470.470590588273, 28895.676997145387, 2440.9389765255833, 2492.1277552856536, 2577.4716183182404, 2605.5179791738587, 2652.485650161881, 17247.396341128126, 17402.738958915626, 17578.38475653359, 17823.197956092008, 17988.55028624282, 1817.7727784026997, 1994.3019943019972, 2002.4393169912303, 2016.0927582587747, 2110.9577808443746, 119493.0, 142455.0, 150420.0, 153045.0, 153440.2327988364, 21098.367048988555, 22409.77590224077, 22442.32673019812, 22551.0, 22651.43371415728, 36441.635583644354, 36442.63557364445, 36458.63541364606, 36527.817360913024, 36535.63464365376, 10846.078083362845, 11337.60318388869, 11348.432578371048, 11358.659240222642, 11538.596149134908, 9079.409838360485, 9097.1812536872, 9130.908690913006, 9244.306676999251, 9289.72130836076, 9997.850032249522, 10039.196864250842, 10047.648332308334, 10174.440405777754, 10175.592976281014, 3112.642046164675, 3830.674392676598, 3873.3802591585213, 3974.841006359771, 4044.312466880637, 715.9251016882398, 794.241499368102, 817.3257062923112, 927.9257659387367, 959.932804703664, 20215.49461263451, 21165.576688466146, 21468.769953423278, 21563.460913477287, 21569.352919412442, 904.1726819959795, 974.1622204903778, 1017.4607458047246, 1021.8875923648393, 1024.2318261304022, 11013.614523491638, 11302.265352752209, 11313.773724525463, 11330.093592512743, 11335.433228338716, 598030.0, 605763.0, 612201.0, 616265.0, 617598.0, 108464.91535084706, 138320.0, 140669.0, 141260.0, 143735.0, 1017.1252722658508, 1018.378788938732, 1072.4905669806935, 1097.040089921319, 1097.780443911221, 18074.819251807312, 18422.171002305196, 18457.077146142907, 18597.44207673772, 18988.525286867938, 44064.77967610142, 51469.71325716737, 53971.0, 56653.71673141773, 57605.0, 78087.6095619541, 78313.0, 84756.57621711852, 85151.0, 85579.71630425424, 7060.54106483077, 8366.24703776663, 8444.493330400195, 8490.0, 8500.064992850905, 9560.282978776759, 13206.867931320949, 13334.13328133707, 13355.332233388486, 13439.865601344252, 11446.141539384746, 11448.713782155513, 11507.194496385173, 11507.424628768695, 11603.477843496894, 11231.887681123084, 11367.20429569922, 11398.373089480157, 11467.713307167387, 11506.07951363872, 9313.25493960495, 11499.080073593925, 11600.30398176112, 11764.529418823151, 11770.3526306054, 7057.470689698396, 8254.876176857471, 8357.373197010127, 8406.537640429835, 8445.831083378174, 10690.89309106899, 11273.661790146309, 11507.769844603228, 11553.480093395985, 11581.88418115808], "value": 10901.016355464624, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1551.968960620788, 1599.0565566315872, 1823.1431227323153, 1953.8632295739303, 1973.9506512337182, 8635.69775057873, 8979.0, 9135.908640913589, 9136.360454768168, 9140.542972851355, 7896.447248692594, 8113.310368618674, 8595.871061934076, 8857.557122143884, 8931.151540603649, 2333.509962907783, 2360.433495960974, 2392.892319845611, 2403.194929698553, 2403.927882163529, 2361.95276094478, 2370.146747171017, 2407.8675672837985, 2409.2170044735444, 2415.2271273192496, 2082.1151010820417, 2156.568686262746, 2159.0500179920864, 2159.762426133124, 2162.751283602382, 2315.8031567316716, 2384.546936082139, 2386.8925898334614, 2397.113068164773, 2398.052769156191, 2366.3137690069857, 2367.633016882369, 2375.6340104440014, 2389.0682633772844, 2392.569337519246, 7900.723474678359, 8707.564621768886, 8935.955320223422, 9065.048169942193, 9071.863922041175, 5170.534651881311, 5376.274202982626, 5383.73081345935, 5384.6769193848495, 5394.703291318976, 3551.396262635358, 3579.355715971124, 3620.348337299285, 3623.927521449556, 3645.2891686121216, 1069.3851035654452, 1070.7269646240236, 1083.0793825248554, 1086.8098082835472, 1089.0851684584986, 12031.578894738643, 12114.152009359348, 12118.6364409067, 12123.151379403445, 12157.878421215852, 4407.405000324915, 4668.95331046692, 4861.124997500413, 4877.219644856877, 4882.731449770296, 10356.585736570452, 10372.377657340432, 10380.117689996285, 10380.688579342632, 10391.948040259902, 5432.728363581805, 5437.755301011385, 5438.265834112344, 5441.374241962225, 5458.263134476874, 4914.557689807934, 4934.802607895644, 4966.230234313687, 4971.055499455092, 4980.228064649913, 3866.9226615467533, 3880.6507414332846, 3923.6468717815533, 3936.901577460529, 3977.76133431995, 1254.8933340666147, 1270.1490001698699, 1273.6561128495255, 1279.174932168746, 1285.6271681212343, 930.5998420679038, 936.6347124621403, 942.5475771629656, 963.8265112279857, 982.6806287956442, 4723.126221649006, 6079.148919151322, 6196.039613859859, 6199.504039676815, 6237.438630523184, 445.5544455544456, 456.8081405809568, 464.1436549566113, 464.4728233455053, 466.2958932012618, 4048.0, 4059.3505039193587, 4108.548059713429, 4146.688998325078, 4172.4993000840095, 10595.311304765166, 10641.0, 10993.505292261867, 11011.394373309546, 11019.393933333744, 10934.945325273482, 10945.4527273636, 10954.178436617127, 10963.83554246687, 10975.50610222542, 7965.283124518705, 8259.504429734236, 8334.416590838584, 8354.373421993374, 8387.287080598218, 6096.481799047131, 6152.569320147547, 6196.225471816023, 6222.968885155546, 6305.243370795534, 10192.0, 10234.948825255826, 10332.225083118947, 10333.379997200193, 10351.896481035094, 9998.950005250219, 10272.435016074187, 10529.10502607287, 10564.894351056699, 10602.469876506299, 7697.114831794415, 7759.573223472763, 7819.569923654026, 7834.294913457816, 7840.3335716464735, 7968.601569921365, 8312.25189732927, 8316.085230624747, 8437.198466145706, 8462.0, 7314.853702925805, 7534.434917381109, 7599.088109426904, 7622.31399174077, 7661.387089032753, 7408.888866667112, 7421.257874212535, 7485.812854678677, 7544.47188696786, 7546.3962882968135, 7029.824254393681, 7146.464015198777, 7214.494985350975, 7219.0, 7219.8556028880175, 4985.202367621163, 5057.873553161053, 5071.619628527801, 5124.1032819257225, 5220.21696745476, 4914.0908931847725, 5084.694918304914, 5145.9228111579105, 5156.587473002226, 5299.814506492255], "value": 4584.657868756978, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.3932360808647, 304.85367023828564, 305.4883070856315, 315.1522404731272, 318.20448877805484, 5101.413337466192, 5225.921611175831, 5340.706261155635, 5385.757640906154, 5417.778710728602, 4331.740095594267, 5189.974050129754, 5330.173823057423, 5331.466853314667, 5356.866078348034, 3507.9298414031705, 3729.9813500932455, 3886.31989401855, 3956.9423899572207, 4063.8577649782264, 3846.615338466159, 3928.626780455852, 4110.362893751473, 4111.787022828273, 4132.793360331986, 4049.3521036634143, 4054.939175912349, 4066.593340665939, 4074.144429669758, 4076.8788583139576, 3347.6484969078383, 3852.495877679503, 4083.571225021389, 4086.7139300248996, 4089.591040895916, 3446.0357596170193, 3544.8227588620466, 3576.6423357664285, 3585.928281434357, 3588.2644057968055, 5193.37679478465, 5200.323957885488, 5227.79088836446, 5240.633155679104, 5242.213667949838, 3664.5236119304586, 4638.605718513931, 4652.976735116303, 4653.581177694024, 4674.976625116887, 4837.806487740486, 5414.187871819219, 5537.58468114893, 5605.943940560624, 5631.436856314377, 3423.4252243967803, 3744.8314825832904, 3911.1395492991496, 3933.1347103637154, 3943.245255861143, 4832.565069143794, 5895.233619629421, 5908.804748628436, 5912.822615321547, 5916.201312822757, 3815.4467602197524, 3997.6801855851463, 4014.8996275092773, 4019.834248068086, 4042.4138499917935, 2994.9517668816175, 3329.583802024747, 3853.8265778040054, 3998.7800670963375, 4076.3477843544892, 4434.84478043267, 4566.3150527420485, 4575.542445755398, 4589.380433641482, 4606.654500912445, 5452.754626041838, 5573.749181286852, 5617.522510586564, 5692.800751973661, 5712.542996560265, 1388.3617331548667, 1410.7813288940026, 1411.3578321863527, 1415.348939487847, 1415.5965549818252, 797.8962734844433, 867.5011868175698, 922.6217250927087, 926.1988380051342, 926.6200857648332, 4851.320815085891, 5120.334356533627, 5217.556507696889, 5335.653182543122, 5358.490943360374, 4952.331435256267, 5789.652620842763, 5833.766649333979, 5847.736851841677, 5853.9414605854245, 4149.647279981175, 4590.380298659638, 5426.972865135649, 5447.105319696436, 5456.454354564512, 2798.5522316429274, 2933.965797029085, 3046.3450358172654, 3070.5282247790296, 3083.2908431060982, 5806.68063256525, 6052.182955300978, 6065.0, 6075.908861367083, 6084.756609735649, 5552.639078459887, 5556.361018482926, 5606.028804733498, 5994.820155395345, 6027.432867454493, 5084.07665592286, 5278.305186369556, 6003.7898673546215, 6028.728707208186, 6041.190846256512, 4858.489858564941, 5328.6944698549105, 5473.945260547343, 5550.307156317362, 5838.985549985248, 5011.774470148925, 5486.204500347507, 5733.971330143323, 5741.282339707536, 5757.625754325955, 4950.975245123751, 5552.222688823487, 5699.715014249188, 6034.788782392595, 6049.909251361321, 3355.5134505497053, 3873.593272706325, 3891.8248678810087, 3915.823787929493, 3919.510061242345, 4740.525947405232, 5697.658140511582, 5706.061378390049, 5837.824865254049, 5844.795432160024, 4952.4057113146655, 5671.773129074791, 5687.687177205294, 5690.374058853606, 5697.401772813795, 4644.674872758875, 4938.38270216223, 5648.0, 5663.745131469176, 5677.3471050829685, 4655.627549796076, 5521.171824226398, 5618.628822706343, 5661.490465857928, 5663.858403539944, 5682.971585142048, 5705.600607957569, 5754.992876246556, 5772.7690892363835, 5793.3337666167245, 4672.626189904868, 4807.6394270429155, 5342.839714808562, 5644.322681278273, 5664.773409063756], "value": 4117.491376578459, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.6855438003809, 730.6154836584669, 806.4596720197471, 811.6388207247774, 845.8181490979438, 14828.77756833647, 16599.502014939557, 16601.33594656212, 16628.75056874145, 16655.999999999985, 15072.547823565283, 15206.23968801561, 15249.161296128708, 15268.618284542892, 15825.920870395632, 893.9374243802953, 909.6406919266885, 922.0042354257386, 922.423485321674, 922.9538523073819, 701.1726163127507, 732.9816754581125, 906.9727908162765, 909.1317791509126, 910.0717268386242, 770.1912991359079, 771.533222400448, 771.9189485104067, 918.0, 967.7435479597913, 950.0357137505438, 950.6577632052456, 951.3435729346763, 952.1525842000625, 954.5036580977853, 702.0276916470725, 702.178451212078, 702.3082263969985, 702.7540360873704, 703.3985942019551, 12701.174423662433, 13781.724365512633, 15645.921770391189, 15658.982166159278, 15686.607834804106, 12949.287789171593, 14339.78490322646, 14409.06341087826, 14682.926585367111, 14788.848113052498, 11743.47154378055, 11948.880511194951, 12058.819117713241, 12062.0, 12447.688807779787, 3852.7303088783797, 3856.67218286446, 3858.112634094146, 3884.961150388488, 4032.3145065338963, 95848.0, 99131.0086899129, 114006.0, 115832.42083789612, 117733.0, 11283.266587671775, 11525.654230373102, 11569.768604628029, 11661.18371713989, 11670.416479176007, 22589.548209036057, 22602.0, 22611.095556177897, 22653.546929061325, 22666.093356265894, 8271.33829293655, 8280.58597070144, 8327.625256863335, 8476.830463390697, 8551.786205344915, 4425.380446737459, 4524.411826462539, 4678.157931572282, 4686.859394218179, 4756.738379389098, 3555.520004799371, 3694.5935947045805, 3710.461983012448, 3711.3690672585726, 3718.200586873835, 567.9687617181095, 688.7176257734405, 696.1611258433677, 696.1646024770296, 696.2619623199323, 532.6777299733683, 570.922925405065, 573.7217449536971, 679.2087218390519, 687.656171914043, 8589.785255368666, 8952.060033696498, 9252.629894804268, 9442.858357124649, 9600.567974441166, 660.9173853268342, 661.2626920983165, 675.9864802703919, 684.3327755438407, 706.7738323736457, 4676.158291507493, 4680.765961701969, 4725.929111063337, 4736.644751643572, 4792.904141917142, 156502.0, 157616.0, 157721.2113939446, 157894.0, 159167.0, 98929.50535247277, 134290.0, 134536.32731836278, 136351.31824341012, 139622.30188848992, 112854.0, 118569.0, 119597.0, 121226.0, 121423.0, 9678.70963871085, 9776.21790256792, 9788.951055244679, 9862.117783117166, 10178.6437474691, 61154.0, 62860.0, 62888.68555657192, 65232.69534609376, 65663.0, 98812.0, 99662.0, 100259.49870250603, 100509.0, 101153.49423252836, 11725.941370293094, 13487.393067311797, 13491.325433728078, 14036.0, 14152.78770818459, 15009.849901500846, 15607.921960390126, 15613.84386156124, 15682.607934801721, 16173.4339298124, 14154.363053662404, 14303.499377521737, 14451.349689263798, 14456.85543144555, 14924.85075149278, 13879.306034698022, 14320.856791431952, 14467.565973020404, 14784.704305914036, 14975.550733478012, 11949.402529873645, 13812.723745524832, 14002.0, 14116.0, 14438.494652687106, 10943.781124377627, 11609.245399049034, 11775.587854425397, 11846.703832404259, 11912.3601332347, 9354.95322523383, 11528.82706759416, 11625.18623696333, 11648.126390520576, 11782.03240058917], "value": 8076.233655860829, "unit": "score"}], "name": "dromaeo_css", "value": 7595.497178672559, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 114.0, 115.0, 114.0, 112.0, 113.0, 112.0, 113.0, 114.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 106.0, 107.0, 158.0, 125.0, 136.0, 128.0, 107.0, 163.0, 109.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 184.0, 185.0, 184.0, 184.0, 180.0, 183.0, 185.0, 175.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 58.0, 87.0, 59.0, 57.0, 61.0, 67.0, 82.0, 56.0], "value": 68.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 91.0, 72.0, 69.0, 68.0, 96.0, 69.0, 68.0, 97.0], "value": 79.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 94.0, 130.0, 88.0, 85.0, 87.0, 84.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 96.0, 93.0, 93.0, 95.0, 94.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 93.0, 136.0, 86.0, 84.0, 85.0, 87.0, 84.0, 87.0, 89.0], "value": 91.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 51.0, 55.0, 53.0, 55.0, 53.0, 53.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 76.0, 76.0, 72.0, 70.0, 70.0, 70.0, 70.0, 72.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 85.0, 83.0, 91.0, 100.0, 79.0, 82.0, 84.0, 83.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 151.0, 152.0, 130.0, 142.0, 141.0, 127.0, 117.0, 188.0, 142.0], "value": 143.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 168.0, 168.0, 171.0, 169.0, 167.0, 169.0, 173.0, 172.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 70.0, 83.0, 88.0, 72.0, 68.0, 67.0, 70.0], "value": 72.0, "unit": "score"}], "name": "kraken", "value": 1443.4999999999998, "alertThreshold": 2.0}]} 16:30:10 INFO - Return code: 0 16:30:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:30:10 INFO - # TBPL SUCCESS # 16:30:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:30:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:30:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:30:10 INFO - 2016-07-19 16:30:10.380 screenresolution[1662:9632] starting screenresolution argv=screenresolution get 16:30:10 INFO - 2016-07-19 16:30:10.382 screenresolution[1662:9632] Display 0: 1600x1200x32@60 16:30:10 INFO - 2016-07-19 16:30:10.389 screenresolution[1663:9635] starting screenresolution argv=screenresolution list 16:30:10 INFO - Available Modes on Display 0 16:30:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:30:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:30:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:30:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:30:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:30:10 INFO - Intel Iris: 16:30:10 INFO - Chipset Model: Intel Iris 16:30:10 INFO - Type: GPU 16:30:10 INFO - Bus: Built-In 16:30:10 INFO - VRAM (Dynamic, Max): 1536 MB 16:30:10 INFO - Vendor: Intel (0x8086) 16:30:10 INFO - Device ID: 0x0a2e 16:30:10 INFO - Revision ID: 0x0009 16:30:10 INFO - Displays: 16:30:10 INFO - Display: 16:30:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:30:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:30:10 INFO - Display Serial Number: 1600x1200 60 16:30:10 INFO - Main Display: Yes 16:30:10 INFO - Mirror: Off 16:30:10 INFO - Online: Yes 16:30:10 INFO - Rotation: Supported 16:30:10 INFO - Return code: 0 16:30:10 INFO - Running post-action listener: _resource_record_post_action 16:30:10 INFO - [mozharness: 2016-07-19 23:30:10.632095Z] Finished run-tests step (success) 16:30:10 INFO - Running post-run listener: _resource_record_post_run 16:30:10 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 37032960; Write bytes: 702040576; Read time: 240; Write time: 1945 16:30:10 INFO - TinderboxPrint: CPU usage
53.2% 16:30:10 INFO - TinderboxPrint: I/O read bytes / time
37,032,960 / 240 16:30:10 INFO - TinderboxPrint: I/O write bytes / time
702,040,576 / 1,945 16:30:10 INFO - TinderboxPrint: CPU idle
2,264.4 (46.8%) 16:30:10 INFO - TinderboxPrint: CPU system
957.7 (19.8%) 16:30:10 INFO - TinderboxPrint: CPU user
1,619.5 (33.4%) 16:30:10 INFO - TinderboxPrint: Swap in / out
444,727,296 / 0 16:30:10 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391123456; Write bytes: 384951808; Read time: 29237; Write time: 780 16:30:10 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 36049920; Write bytes: 291902464; Read time: 226; Write time: 1103 16:30:11 INFO - Running post-run listener: _upload_blobber_files 16:30:11 INFO - Blob upload gear active. 16:30:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:30:11 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 16:30:11 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'] 16:30:11 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 16:30:11 INFO - (blobuploader) - INFO - Open directory for files ... 16:30:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:30:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:30:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:12 INFO - (blobuploader) - INFO - Done attempting. 16:30:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:30:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:30:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:13 INFO - (blobuploader) - INFO - Done attempting. 16:30:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:30:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:30:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:21 INFO - (blobuploader) - INFO - Done attempting. 16:30:21 INFO - (blobuploader) - INFO - Iteration through files over. 16:30:21 INFO - Return code: 0 16:30:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:30:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:30:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/58a605aa67c496a72b93d479f27210513c4b14f65b28e47a797dfae4236b8ab35a488a3501e05a74d54d8b0ffbe23df6feeabab975ed67f40795488e1f0ff957", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/91faa75b3c08d4c64fdb271d7ace92d32b403c5ed80b847cfb32769bea7dc38f2919caed805da89f958b00876f2a15bd9447ea213e59a74032badc8882abfd9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fac66b4faabe27ff34dc29ed9269b333cfa295c343723008dc70486316c6788f28aa679fcad30f375d3883c2930bcfedf224ed3ff38a14931e667a805a781801"} 16:30:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:30:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:30:21 INFO - Contents: 16:30:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/58a605aa67c496a72b93d479f27210513c4b14f65b28e47a797dfae4236b8ab35a488a3501e05a74d54d8b0ffbe23df6feeabab975ed67f40795488e1f0ff957", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/91faa75b3c08d4c64fdb271d7ace92d32b403c5ed80b847cfb32769bea7dc38f2919caed805da89f958b00876f2a15bd9447ea213e59a74032badc8882abfd9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fac66b4faabe27ff34dc29ed9269b333cfa295c343723008dc70486316c6788f28aa679fcad30f375d3883c2930bcfedf224ed3ff38a14931e667a805a781801"} 16:30:21 INFO - Running post-run listener: copy_logs_to_upload_dir 16:30:21 INFO - Copying logs to upload dir... 16:30:21 INFO - mkdir: /builds/slave/test/build/upload/logs 16:30:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.948789 ========= master_lag: 0.42 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-07-19 16:30:21.959830) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 18 secs) (at 2016-07-19 16:30:21.967313) ========= 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.334yaFM6YT/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.lJQQkX81Xt/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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/58a605aa67c496a72b93d479f27210513c4b14f65b28e47a797dfae4236b8ab35a488a3501e05a74d54d8b0ffbe23df6feeabab975ed67f40795488e1f0ff957", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/91faa75b3c08d4c64fdb271d7ace92d32b403c5ed80b847cfb32769bea7dc38f2919caed805da89f958b00876f2a15bd9447ea213e59a74032badc8882abfd9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fac66b4faabe27ff34dc29ed9269b333cfa295c343723008dc70486316c6788f28aa679fcad30f375d3883c2930bcfedf224ed3ff38a14931e667a805a781801"} build_url:https://queue.taskcluster.net/v1/task/UNuaa5PaSdCAnCAwUu4wIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010507 build_url: 'https://queue.taskcluster.net/v1/task/UNuaa5PaSdCAnCAwUu4wIA/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/58a605aa67c496a72b93d479f27210513c4b14f65b28e47a797dfae4236b8ab35a488a3501e05a74d54d8b0ffbe23df6feeabab975ed67f40795488e1f0ff957", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/91faa75b3c08d4c64fdb271d7ace92d32b403c5ed80b847cfb32769bea7dc38f2919caed805da89f958b00876f2a15bd9447ea213e59a74032badc8882abfd9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fac66b4faabe27ff34dc29ed9269b333cfa295c343723008dc70486316c6788f28aa679fcad30f375d3883c2930bcfedf224ed3ff38a14931e667a805a781801"}' ========= master_lag: 18.50 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 18 secs) (at 2016-07-19 16:30:40.478037) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 16:30:40.478435) ========= 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.334yaFM6YT/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.lJQQkX81Xt/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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.005140 ========= master_lag: 1.92 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 16:30:42.406194) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 16:30:42.406614) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 16:30:42.411954) ========= ========= Total master_lag: 20.91 =========