builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0367 starttime: 1469078386.57 results: success (0) buildid: 20160720195820 builduid: 7943b3e53d1744e5880d6bf4687e1372 revision: e25f1a122d9dc433d907ec2d1a6c9108cb3fbec0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 22:19:46.572404) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 22:19:46.572852) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 22:19:46.573124) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 22:19:46.652971) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-20 22:19:46.653235) ========= 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.ehGyWyl1K8/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.JnPkPdByg2/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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-20 22:19:46-- 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% 25.4M=0s 2016-07-20 22:19:46 (25.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132258 ========= master_lag: 2.54 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-20 22:19:49.320948) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 22:19:49.321294) ========= 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.ehGyWyl1K8/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.JnPkPdByg2/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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.025868 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 22:19:49.429254) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-20 22:19:49.429645) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev e25f1a122d9dc433d907ec2d1a6c9108cb3fbec0 --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 e25f1a122d9dc433d907ec2d1a6c9108cb3fbec0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ehGyWyl1K8/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.JnPkPdByg2/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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-20 22:19:49,509 truncating revision to first 12 chars 2016-07-20 22:19:49,509 Setting DEBUG logging. 2016-07-20 22:19:49,509 attempt 1/10 2016-07-20 22:19:49,509 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/e25f1a122d9d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-20 22:19:50,596 unpacking tar archive at: autoland-e25f1a122d9d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.375745 ========= master_lag: 0.18 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-20 22:19:50.989903) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 22:19:50.990227) ========= script_repo_revision: e25f1a122d9dc433d907ec2d1a6c9108cb3fbec0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 22:19:50.990597) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 22:19:50.990884) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 22:19:51.012281) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-20 22:19:51.012638) ========= /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.ehGyWyl1K8/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.JnPkPdByg2/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 22:19:51 INFO - MultiFileLogger online at 20160720 22:19:51 in /builds/slave/test 22:19:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 22:19:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:19:51 INFO - {'append_to_log': False, 22:19:51 INFO - 'base_work_dir': '/builds/slave/test', 22:19:51 INFO - 'blob_upload_branch': 'Autoland', 22:19:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:19:51 INFO - 'branch': 'Autoland', 22:19:51 INFO - 'buildbot_json_path': 'buildprops.json', 22:19:51 INFO - 'config_files': ('talos/mac_config.py',), 22:19:51 INFO - 'default_actions': ('clobber', 22:19:51 INFO - 'read-buildbot-config', 22:19:51 INFO - 'download-and-extract', 22:19:51 INFO - 'populate-webroot', 22:19:51 INFO - 'create-virtualenv', 22:19:51 INFO - 'install', 22:19:51 INFO - 'run-tests'), 22:19:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:19:51 INFO - 'download_minidump_stackwalk': True, 22:19:51 INFO - 'download_symbols': 'ondemand', 22:19:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:19:51 INFO - 'tooltool.py': '/tools/tooltool.py', 22:19:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:19:51 INFO - '/tools/misc-python/virtualenv.py')}, 22:19:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:19:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:19:51 INFO - 'installer_path': 'installer.exe', 22:19:51 INFO - 'log_level': 'info', 22:19:51 INFO - 'log_name': 'talos', 22:19:51 INFO - 'log_to_console': True, 22:19:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:19:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:19:51 INFO - 'opt_config_files': (), 22:19:51 INFO - 'pip_index': False, 22:19:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:19:51 INFO - 'cmd': ('bash', 22:19:51 INFO - '-c', 22:19:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:19:51 INFO - 'enabled': True, 22:19:51 INFO - 'halt_on_failure': False, 22:19:51 INFO - 'name': 'check_screen_resolution'},), 22:19:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:19:51 INFO - 'cmd': ('bash', 22:19:51 INFO - '-c', 22:19:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:19:51 INFO - 'enabled': True, 22:19:51 INFO - 'halt_on_failure': False, 22:19:51 INFO - 'name': 'check_screen_resolution'},), 22:19:51 INFO - 'run_cmd_checks_enabled': True, 22:19:51 INFO - 'sps_profile': False, 22:19:51 INFO - 'sps_profile_interval': 0, 22:19:51 INFO - 'suite': 'dromaeojs', 22:19:51 INFO - 'system_bits': '32', 22:19:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:19:51 INFO - 'title': 't-yosemite-r7-0367', 22:19:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:19:51 INFO - 'use_talos_json': True, 22:19:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:19:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:19:51 INFO - 'work_dir': 'build'} 22:19:51 INFO - [mozharness: 2016-07-21 05:19:51.199510Z] Running clobber step. 22:19:51 INFO - Running pre-action listener: _resource_record_pre_action 22:19:51 INFO - Running main action method: clobber 22:19:51 INFO - rmtree: /builds/slave/test/build 22:19:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:19:53 INFO - Running post-action listener: _resource_record_post_action 22:19:53 INFO - [mozharness: 2016-07-21 05:19:53.217746Z] Finished clobber step (success) 22:19:53 INFO - [mozharness: 2016-07-21 05:19:53.217988Z] Running read-buildbot-config step. 22:19:53 INFO - Running pre-action listener: _resource_record_pre_action 22:19:53 INFO - Running main action method: read_buildbot_config 22:19:53 INFO - Using buildbot properties: 22:19:53 INFO - { 22:19:53 INFO - "project": "", 22:19:53 INFO - "product": "firefox", 22:19:53 INFO - "script_repo_revision": "production", 22:19:53 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 22:19:53 INFO - "repository": "", 22:19:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 22:19:53 INFO - "buildid": "20160720195820", 22:19:53 INFO - "pgo_build": "False", 22:19:53 INFO - "basedir": "/builds/slave/test", 22:19:53 INFO - "buildnumber": 20, 22:19:53 INFO - "slavename": "t-yosemite-r7-0367", 22:19:53 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:19:53 INFO - "platform": "yosemite_r7", 22:19:53 INFO - "scheduler": "tests-autoland-macosx64-talos", 22:19:53 INFO - "branch": "autoland", 22:19:53 INFO - "repo_path": "integration/autoland", 22:19:53 INFO - "revision": "e25f1a122d9dc433d907ec2d1a6c9108cb3fbec0", 22:19:53 INFO - "stage_platform": "macosx64", 22:19:53 INFO - "builduid": "7943b3e53d1744e5880d6bf4687e1372", 22:19:53 INFO - "slavebuilddir": "test" 22:19:53 INFO - } 22:19:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 22:19:53 INFO - Running post-action listener: _resource_record_post_action 22:19:53 INFO - [mozharness: 2016-07-21 05:19:53.222289Z] Finished read-buildbot-config step (success) 22:19:53 INFO - [mozharness: 2016-07-21 05:19:53.222436Z] Running download-and-extract step. 22:19:53 INFO - Running pre-action listener: _resource_record_pre_action 22:19:53 INFO - Running main action method: download_and_extract 22:19:53 INFO - mkdir: /builds/slave/test/build/tests 22:19: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')]} 22:19:53 INFO - https://queue.taskcluster.net/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:19:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 22:19:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 22:19:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 22:19:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 22:19:54 INFO - Downloaded 1199 bytes. 22:19:54 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 22:19:54 INFO - Using the following test package requirements: 22:19:54 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 22:19:54 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:19:54 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 22:19:54 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:19:54 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 22:19:54 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:19:54 INFO - u'jsshell-mac.zip'], 22:19:54 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:19:54 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 22:19:54 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 22:19:54 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:19:54 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 22:19:54 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:19:54 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 22:19:54 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:19:54 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 22:19:54 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:19:54 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 22:19:54 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:54 INFO - https://queue.taskcluster.net/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:19:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:19:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:19:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:19:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:19:55 INFO - Downloaded 18800940 bytes. 22:19:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:19:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:19:59 INFO - Return code: 0 22:19:59 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:59 INFO - https://queue.taskcluster.net/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:19:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:19:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:19:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:19:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:20:00 INFO - Downloaded 18800940 bytes. 22:20:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:20:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:20:04 INFO - Return code: 0 22:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:04 INFO - https://queue.taskcluster.net/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:20:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:20:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:20:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:20:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:20:05 INFO - Downloaded 14403317 bytes. 22:20:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:20:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:20:05 INFO - Return code: 0 22:20:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:05 INFO - https://queue.taskcluster.net/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:20:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:20:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:20:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 22:20:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 22:20:08 INFO - Downloaded 112880566 bytes. 22:20:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:20:08 INFO - mkdir: /builds/slave/test/properties 22:20:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:20:08 INFO - Writing to file /builds/slave/test/properties/build_url 22:20:08 INFO - Contents: 22:20:08 INFO - build_url:https://queue.taskcluster.net/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:20:09 INFO - Running post-action listener: _resource_record_post_action 22:20:09 INFO - Running post-action listener: set_extra_try_arguments 22:20:09 INFO - [mozharness: 2016-07-21 05:20:09.301443Z] Finished download-and-extract step (success) 22:20:09 INFO - [mozharness: 2016-07-21 05:20:09.301619Z] Running populate-webroot step. 22:20:09 INFO - Running pre-action listener: _resource_record_pre_action 22:20:09 INFO - Running main action method: populate_webroot 22:20:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:20:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:20:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:20:09 INFO - '--tppagecycles', 22:20:09 INFO - '7'], 22:20:09 INFO - 'tests': ['tsvgm']}}, 22:20:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:20:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:20:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:20:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:20:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:20:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:20:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:20:09 INFO - 'talos_options': ['--disable-e10s'], 22:20:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:20:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:20:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:20:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:20:09 INFO - 'talos_options': ['--disable-e10s'], 22:20:09 INFO - 'tests': ['damp', 'tps']}, 22:20:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:20:09 INFO - 'tests': ['damp', 'tps']}, 22:20:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:20:09 INFO - 'tests': ['dromaeo_dom']}, 22:20:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:20:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:20:09 INFO - 'tests': ['basic_compositor_video']}, 22:20:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:20:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:20:09 INFO - 'tests': ['a11yr', 22:20:09 INFO - 'ts_paint', 22:20:09 INFO - 'tpaint', 22:20:09 INFO - 'sessionrestore', 22:20:09 INFO - 'sessionrestore_no_auto_restore', 22:20:09 INFO - 'tabpaint']}, 22:20:09 INFO - 'other-e10s': {'tests': ['a11yr', 22:20:09 INFO - 'ts_paint', 22:20:09 INFO - 'tpaint', 22:20:09 INFO - 'sessionrestore', 22:20:09 INFO - 'sessionrestore_no_auto_restore', 22:20:09 INFO - 'tabpaint']}, 22:20:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:20:09 INFO - 'ts_paint', 22:20:09 INFO - 'tpaint', 22:20:09 INFO - 'sessionrestore', 22:20:09 INFO - 'sessionrestore_no_auto_restore', 22:20:09 INFO - 'tabpaint']}, 22:20:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:20:09 INFO - 'ts_paint', 22:20:09 INFO - 'tpaint', 22:20:09 INFO - 'sessionrestore', 22:20:09 INFO - 'sessionrestore_no_auto_restore', 22:20:09 INFO - 'tabpaint']}, 22:20:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:20:09 INFO - 'tests': ['a11yr', 22:20:09 INFO - 'ts_paint', 22:20:09 INFO - 'tpaint', 22:20:09 INFO - 'sessionrestore', 22:20:09 INFO - 'sessionrestore_no_auto_restore', 22:20:09 INFO - 'tabpaint']}, 22:20:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:20:09 INFO - 'tests': ['a11yr', 22:20:09 INFO - 'ts_paint', 22:20:09 INFO - 'tpaint', 22:20:09 INFO - 'sessionrestore', 22:20:09 INFO - 'sessionrestore_no_auto_restore', 22:20:09 INFO - 'tabpaint']}, 22:20:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:20:09 INFO - 'tests': ['tsvgx', 22:20:09 INFO - 'tsvgr_opacity', 22:20:09 INFO - 'tart', 22:20:09 INFO - 'tscrollx', 22:20:09 INFO - 'cart']}, 22:20:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:20:09 INFO - 'tsvgr_opacity', 22:20:09 INFO - 'tart', 22:20:09 INFO - 'tscrollx', 22:20:09 INFO - 'cart']}, 22:20:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:20:09 INFO - 'talos_options': ['--disable-e10s'], 22:20:09 INFO - 'tests': ['tp5o']}, 22:20:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:20:09 INFO - 'tests': ['tp5o']}, 22:20:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:20:09 INFO - 'talos_options': ['--disable-e10s', 22:20:09 INFO - '--xperf_path', 22:20:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:20:09 INFO - 'tests': ['tp5n']}, 22:20:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:20:09 INFO - 'talos_options': ['--xperf_path', 22:20:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:20:09 INFO - 'tests': ['tp5n']}}, 22:20:09 INFO - 'talos.zip': {'path': '', 22:20:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:20:09 INFO - Running post-action listener: _resource_record_post_action 22:20:09 INFO - [mozharness: 2016-07-21 05:20:09.320780Z] Finished populate-webroot step (success) 22:20:09 INFO - [mozharness: 2016-07-21 05:20:09.320887Z] Running create-virtualenv step. 22:20:09 INFO - Running pre-action listener: _resource_record_pre_action 22:20:09 INFO - Running main action method: create_virtualenv 22:20:09 INFO - Creating virtualenv /builds/slave/test/build/venv 22:20:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:20:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:20:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:20:09 INFO - Using real prefix '/tools/python27' 22:20:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:20:10 INFO - Installing distribute.............................................................................................................................................................................................done. 22:20:13 INFO - Installing pip.................done. 22:20:13 INFO - Return code: 0 22:20:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:13 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 0x104c98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d2d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc24348e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bfcd30>, '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 0x104961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bf4b20>, '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.ehGyWyl1K8/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.JnPkPdByg2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:20:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:20:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ehGyWyl1K8/Render', 22:20:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:20:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:20:13 INFO - 'HOME': '/Users/cltbld', 22:20:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:20:13 INFO - 'LOGNAME': 'cltbld', 22:20:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:13 INFO - 'MOZ_NO_REMOTE': '1', 22:20:13 INFO - 'NO_EM_RESTART': '1', 22:20:13 INFO - 'PAGER': '/bin/cat', 22:20:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:20:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:13 INFO - 'PWD': '/builds/slave/test', 22:20:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:20:13 INFO - 'SHELL': '/bin/bash', 22:20:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnPkPdByg2/Listeners', 22:20:13 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 22:20:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:20:13 INFO - 'USER': 'cltbld', 22:20:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:20:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:20:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:13 INFO - 'XPC_FLAGS': '0x0', 22:20:13 INFO - 'XPC_SERVICE_NAME': '0', 22:20:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:20:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:20:13 INFO - Cleaning up... 22:20:13 INFO - Return code: 0 22:20:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:13 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 0x104c98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d2d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc24348e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bfcd30>, '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 0x104961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bf4b20>, '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.ehGyWyl1K8/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.JnPkPdByg2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:20:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:20:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ehGyWyl1K8/Render', 22:20:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:20:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:20:13 INFO - 'HOME': '/Users/cltbld', 22:20:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:20:13 INFO - 'LOGNAME': 'cltbld', 22:20:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:13 INFO - 'MOZ_NO_REMOTE': '1', 22:20:13 INFO - 'NO_EM_RESTART': '1', 22:20:13 INFO - 'PAGER': '/bin/cat', 22:20:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:20:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:13 INFO - 'PWD': '/builds/slave/test', 22:20:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:20:13 INFO - 'SHELL': '/bin/bash', 22:20:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnPkPdByg2/Listeners', 22:20:13 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 22:20:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:20:13 INFO - 'USER': 'cltbld', 22:20:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:20:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:20:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:13 INFO - 'XPC_FLAGS': '0x0', 22:20:13 INFO - 'XPC_SERVICE_NAME': '0', 22:20:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:20:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:13 INFO - Downloading/unpacking psutil>=3.1.1 22:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:20:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:20:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:20:16 INFO - Installing collected packages: psutil 22:20:16 INFO - Running setup.py install for psutil 22:20:16 INFO - building 'psutil._psutil_osx' extension 22:20:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:20:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:20:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:20:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 22:20:17 INFO - building 'psutil._psutil_posix' extension 22:20:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 22:20:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:20:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:20:17 INFO - ^ 22:20:17 INFO - 1 warning generated. 22:20:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 22:20:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:20:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:20:17 INFO - Successfully installed psutil 22:20:17 INFO - Cleaning up... 22:20:17 INFO - Return code: 0 22:20:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:20:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:17 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 0x104c98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d2d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc24348e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bfcd30>, '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 0x104961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bf4b20>, '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.ehGyWyl1K8/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.JnPkPdByg2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:20:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:20:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:20:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ehGyWyl1K8/Render', 22:20:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:20:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:20:17 INFO - 'HOME': '/Users/cltbld', 22:20:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:20:17 INFO - 'LOGNAME': 'cltbld', 22:20:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:17 INFO - 'MOZ_NO_REMOTE': '1', 22:20:17 INFO - 'NO_EM_RESTART': '1', 22:20:17 INFO - 'PAGER': '/bin/cat', 22:20:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:20:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:17 INFO - 'PWD': '/builds/slave/test', 22:20:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:20:17 INFO - 'SHELL': '/bin/bash', 22:20:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnPkPdByg2/Listeners', 22:20:17 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 22:20:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:20:17 INFO - 'USER': 'cltbld', 22:20:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:20:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:20:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:17 INFO - 'XPC_FLAGS': '0x0', 22:20:17 INFO - 'XPC_SERVICE_NAME': '0', 22:20:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:20:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:20:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:20:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:20:20 INFO - Installing collected packages: mozsystemmonitor 22:20:20 INFO - Running setup.py install for mozsystemmonitor 22:20:20 INFO - Successfully installed mozsystemmonitor 22:20:20 INFO - Cleaning up... 22:20:20 INFO - Return code: 0 22:20:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:20:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:20 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 0x104c98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d2d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc24348e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bfcd30>, '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 0x104961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bf4b20>, '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.ehGyWyl1K8/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.JnPkPdByg2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:20:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:20:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:20:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ehGyWyl1K8/Render', 22:20:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:20:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:20:20 INFO - 'HOME': '/Users/cltbld', 22:20:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:20:20 INFO - 'LOGNAME': 'cltbld', 22:20:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:20 INFO - 'MOZ_NO_REMOTE': '1', 22:20:20 INFO - 'NO_EM_RESTART': '1', 22:20:20 INFO - 'PAGER': '/bin/cat', 22:20:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:20:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:20 INFO - 'PWD': '/builds/slave/test', 22:20:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:20:20 INFO - 'SHELL': '/bin/bash', 22:20:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnPkPdByg2/Listeners', 22:20:20 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 22:20:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:20:20 INFO - 'USER': 'cltbld', 22:20:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:20:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:20:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:20 INFO - 'XPC_FLAGS': '0x0', 22:20:20 INFO - 'XPC_SERVICE_NAME': '0', 22:20:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:20:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:20 INFO - Downloading/unpacking jsonschema==2.5.1 22:20:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:20:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:23 INFO - Downloading functools32-3.2.3-2.tar.gz 22:20:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:20:23 INFO - warning: no files found matching '*.txt' 22:20:23 INFO - no previously-included directories found matching 'build' 22:20:23 INFO - no previously-included directories found matching 'dist' 22:20:23 INFO - no previously-included directories found matching '.git*' 22:20:23 INFO - Installing collected packages: jsonschema, functools32 22:20:23 INFO - Running setup.py install for functools32 22:20:23 INFO - warning: no files found matching '*.txt' 22:20:23 INFO - no previously-included directories found matching 'build' 22:20:23 INFO - no previously-included directories found matching 'dist' 22:20:23 INFO - no previously-included directories found matching '.git*' 22:20:23 INFO - Successfully installed jsonschema functools32 22:20:23 INFO - Cleaning up... 22:20:24 INFO - Return code: 0 22:20:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:24 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 0x104c98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d2d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc24348e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bfcd30>, '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 0x104961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bf4b20>, '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.ehGyWyl1K8/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.JnPkPdByg2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:20:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:20:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:20:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ehGyWyl1K8/Render', 22:20:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:20:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:20:24 INFO - 'HOME': '/Users/cltbld', 22:20:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:20:24 INFO - 'LOGNAME': 'cltbld', 22:20:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:24 INFO - 'MOZ_NO_REMOTE': '1', 22:20:24 INFO - 'NO_EM_RESTART': '1', 22:20:24 INFO - 'PAGER': '/bin/cat', 22:20:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:20:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:24 INFO - 'PWD': '/builds/slave/test', 22:20:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:20:24 INFO - 'SHELL': '/bin/bash', 22:20:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnPkPdByg2/Listeners', 22:20:24 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 22:20:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:20:24 INFO - 'USER': 'cltbld', 22:20:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:20:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:20:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:24 INFO - 'XPC_FLAGS': '0x0', 22:20:24 INFO - 'XPC_SERVICE_NAME': '0', 22:20:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:20:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:24 INFO - Downloading/unpacking blobuploader==1.2.4 22:20:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:26 INFO - Downloading blobuploader-1.2.4.tar.gz 22:20:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:20:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:20:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:27 INFO - Downloading docopt-0.6.1.tar.gz 22:20:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:20:28 INFO - Installing collected packages: blobuploader, requests, docopt 22:20:28 INFO - Running setup.py install for blobuploader 22:20:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:20:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:20:28 INFO - Running setup.py install for requests 22:20:28 INFO - Running setup.py install for docopt 22:20:28 INFO - Successfully installed blobuploader requests docopt 22:20:28 INFO - Cleaning up... 22:20:28 INFO - Return code: 0 22:20:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:28 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 0x104c98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d2d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc24348e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bfcd30>, '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 0x104961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bf4b20>, '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.ehGyWyl1K8/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.JnPkPdByg2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:20:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:20:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:20:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ehGyWyl1K8/Render', 22:20:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:20:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:20:28 INFO - 'HOME': '/Users/cltbld', 22:20:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:20:28 INFO - 'LOGNAME': 'cltbld', 22:20:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:28 INFO - 'MOZ_NO_REMOTE': '1', 22:20:28 INFO - 'NO_EM_RESTART': '1', 22:20:28 INFO - 'PAGER': '/bin/cat', 22:20:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:20:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:28 INFO - 'PWD': '/builds/slave/test', 22:20:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:20:28 INFO - 'SHELL': '/bin/bash', 22:20:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnPkPdByg2/Listeners', 22:20:28 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 22:20:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:20:28 INFO - 'USER': 'cltbld', 22:20:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:20:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:20:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:28 INFO - 'XPC_FLAGS': '0x0', 22:20:28 INFO - 'XPC_SERVICE_NAME': '0', 22:20:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:20:29 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-VT4s1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:20:29 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-VyqiUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:20:29 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-wbJQYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:20:29 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-v4y_IG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:20:29 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-tJiRy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:20:29 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-bG1Njq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:20:29 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-QXpPFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:20:29 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-OU4qy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:20:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:20:30 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-hUZjJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:20:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:20:30 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-G_UZWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:20:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:20:30 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-hZhQwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:20:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:20:30 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-cfJCuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:20:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:20:30 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-6CUhZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:20:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:20:30 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-j7JO05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:20:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:30 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-WB4TnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:20:30 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-sVPrVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:20:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:20:30 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-7bmUPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:20:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:20:30 INFO - Running setup.py install for manifestparser 22:20:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:20:31 INFO - Running setup.py install for mozcrash 22:20:31 INFO - Running setup.py install for mozdebug 22:20:31 INFO - Running setup.py install for mozdevice 22:20:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:20:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:20:31 INFO - Running setup.py install for mozfile 22:20:31 INFO - Running setup.py install for mozhttpd 22:20:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:20:31 INFO - Running setup.py install for mozinfo 22:20:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:20:31 INFO - Running setup.py install for mozInstall 22:20:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:20:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:20:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:20:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:20:32 INFO - Running setup.py install for mozleak 22:20:32 INFO - Running setup.py install for mozlog 22:20:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:20:32 INFO - Running setup.py install for moznetwork 22:20:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:20:32 INFO - Running setup.py install for mozprocess 22:20:32 INFO - Running setup.py install for mozprofile 22:20:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:20:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:20:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:20:32 INFO - Running setup.py install for mozrunner 22:20:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:20:33 INFO - Running setup.py install for mozscreenshot 22:20:33 INFO - Running setup.py install for moztest 22:20:33 INFO - Running setup.py install for mozversion 22:20:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:20:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:20:33 INFO - Cleaning up... 22:20:33 INFO - Return code: 0 22:20:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:20: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')]} 22:20:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20: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')]} 22:20:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:33 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 0x104c98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d2d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc24348e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bfcd30>, '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 0x104961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bf4b20>, '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.ehGyWyl1K8/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.JnPkPdByg2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:20:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:20:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:20:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ehGyWyl1K8/Render', 22:20:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:20:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:20:33 INFO - 'HOME': '/Users/cltbld', 22:20:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:20:33 INFO - 'LOGNAME': 'cltbld', 22:20:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:33 INFO - 'MOZ_NO_REMOTE': '1', 22:20:33 INFO - 'NO_EM_RESTART': '1', 22:20:33 INFO - 'PAGER': '/bin/cat', 22:20:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:20:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:33 INFO - 'PWD': '/builds/slave/test', 22:20:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:20:33 INFO - 'SHELL': '/bin/bash', 22:20:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnPkPdByg2/Listeners', 22:20:33 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 22:20:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:20:33 INFO - 'USER': 'cltbld', 22:20:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:20:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:20:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:33 INFO - 'XPC_FLAGS': '0x0', 22:20:33 INFO - 'XPC_SERVICE_NAME': '0', 22:20:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:20:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:20:33 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-cQPS4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:20:33 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:20:33 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-peSJqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:20:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:20:34 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-ETXp6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:20:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:20:34 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-EMNc30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:20:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:20:34 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-wUjjGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:20:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:20:34 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-s659cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:20:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:20:34 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-9keK7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:20:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:20:34 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-X4bmcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:20:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:20:34 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-7W2EiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:20:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:20:34 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-3TN9xs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:20:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:20:34 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-Ri7dK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:20:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:20:34 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-4x1JEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:20:35 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-ZWvgmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:20:35 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-_6I8id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:35 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-dyiOCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:20:35 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-q5l2a4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:20:35 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-_Ztoeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:20:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:38 INFO - Downloading blessings-1.6.tar.gz 22:20:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:20:38 INFO - Installing collected packages: blessings 22:20:38 INFO - Running setup.py install for blessings 22:20:38 INFO - Successfully installed blessings 22:20:38 INFO - Cleaning up... 22:20:38 INFO - Return code: 0 22:20:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:20:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:20:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:20:38 INFO - Reading from file tmpfile_stdout 22:20:38 INFO - Current package versions: 22:20:38 INFO - blessings == 1.6 22:20:38 INFO - blobuploader == 1.2.4 22:20:38 INFO - docopt == 0.6.1 22:20:38 INFO - functools32 == 3.2.3-2 22:20:38 INFO - jsonschema == 2.5.1 22:20:38 INFO - manifestparser == 1.1 22:20:38 INFO - mozInstall == 1.12 22:20:38 INFO - mozcrash == 0.17 22:20:38 INFO - mozdebug == 0.1 22:20:38 INFO - mozdevice == 0.48 22:20:38 INFO - mozfile == 1.2 22:20:38 INFO - mozhttpd == 0.7 22:20:38 INFO - mozinfo == 0.9 22:20:38 INFO - mozleak == 0.1 22:20:38 INFO - mozlog == 3.3 22:20:38 INFO - moznetwork == 0.27 22:20:38 INFO - mozprocess == 0.23 22:20:38 INFO - mozprofile == 0.28 22:20:38 INFO - mozrunner == 6.12 22:20:38 INFO - mozscreenshot == 0.1 22:20:38 INFO - mozsystemmonitor == 0.3 22:20:38 INFO - moztest == 0.7 22:20:38 INFO - mozversion == 1.4 22:20:38 INFO - psutil == 3.1.1 22:20:38 INFO - requests == 1.2.3 22:20:38 INFO - wsgiref == 0.1.2 22:20:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:20:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:38 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 0x104c98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d2d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc24348e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bfcd30>, '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 0x104961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bf4b20>, '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.ehGyWyl1K8/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.JnPkPdByg2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:20:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:20:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:20:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ehGyWyl1K8/Render', 22:20:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:20:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:20:38 INFO - 'HOME': '/Users/cltbld', 22:20:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:20:38 INFO - 'LOGNAME': 'cltbld', 22:20:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:38 INFO - 'MOZ_NO_REMOTE': '1', 22:20:38 INFO - 'NO_EM_RESTART': '1', 22:20:38 INFO - 'PAGER': '/bin/cat', 22:20:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:20:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:38 INFO - 'PWD': '/builds/slave/test', 22:20:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:20:38 INFO - 'SHELL': '/bin/bash', 22:20:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnPkPdByg2/Listeners', 22:20:38 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 22:20:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:20:38 INFO - 'USER': 'cltbld', 22:20:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:20:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:20:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:38 INFO - 'XPC_FLAGS': '0x0', 22:20:38 INFO - 'XPC_SERVICE_NAME': '0', 22:20:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:20:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:20:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:20:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:20:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:20:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:20:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:20:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:20:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:20:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:20:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:20:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:20:38 INFO - Cleaning up... 22:20:38 INFO - Return code: 0 22:20:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:20:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:38 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 0x104c98a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d2d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc24348e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bfcd30>, '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 0x104961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bf4b20>, '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.ehGyWyl1K8/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.JnPkPdByg2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:20:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:20:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:20:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ehGyWyl1K8/Render', 22:20:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:20:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:20:38 INFO - 'HOME': '/Users/cltbld', 22:20:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:20:38 INFO - 'LOGNAME': 'cltbld', 22:20:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:38 INFO - 'MOZ_NO_REMOTE': '1', 22:20:38 INFO - 'NO_EM_RESTART': '1', 22:20:38 INFO - 'PAGER': '/bin/cat', 22:20:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:20:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:38 INFO - 'PWD': '/builds/slave/test', 22:20:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:20:38 INFO - 'SHELL': '/bin/bash', 22:20:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnPkPdByg2/Listeners', 22:20:38 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 22:20:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:20:38 INFO - 'USER': 'cltbld', 22:20:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:20:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:20:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:38 INFO - 'XPC_FLAGS': '0x0', 22:20:38 INFO - 'XPC_SERVICE_NAME': '0', 22:20:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:20:39 INFO - Cleaning up... 22:20:39 INFO - Return code: 0 22:20:39 INFO - Running post-action listener: _resource_record_post_action 22:20:39 INFO - Running post-action listener: _start_resource_monitoring 22:20:39 INFO - Starting resource monitoring. 22:20:39 INFO - [mozharness: 2016-07-21 05:20:39.244645Z] Finished create-virtualenv step (success) 22:20:39 INFO - [mozharness: 2016-07-21 05:20:39.245573Z] Running install step. 22:20:39 INFO - Running pre-action listener: _resource_record_pre_action 22:20:39 INFO - Running main action method: install 22:20:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:20:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:20:39 INFO - Reading from file tmpfile_stdout 22:20:39 INFO - Detecting whether we're running mozinstall >=1.0... 22:20:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:20:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:20:39 INFO - Reading from file tmpfile_stdout 22:20:39 INFO - Output received: 22:20:39 INFO - Usage: mozinstall [options] installer 22:20:39 INFO - Options: 22:20:39 INFO - -h, --help show this help message and exit 22:20:39 INFO - -d DEST, --destination=DEST 22:20:39 INFO - Directory to install application into. [default: 22:20:39 INFO - "/builds/slave/test"] 22:20:39 INFO - --app=APP Application being installed. [default: firefox] 22:20:39 INFO - mkdir: /builds/slave/test/build/application 22:20:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:20:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:21:14 INFO - Reading from file tmpfile_stdout 22:21:14 INFO - Output received: 22:21:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:21:14 INFO - Running post-action listener: _resource_record_post_action 22:21:14 INFO - [mozharness: 2016-07-21 05:21:14.009114Z] Finished install step (success) 22:21:14 INFO - [mozharness: 2016-07-21 05:21:14.009248Z] Running run-tests step. 22:21:14 INFO - Running pre-action listener: _resource_record_pre_action 22:21:14 INFO - Running main action method: run_tests 22:21:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:21:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:21:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:21:14 INFO - 2016-07-20 22:21:14.023 screenresolution[1637:7404] starting screenresolution argv=screenresolution get 22:21:14 INFO - 2016-07-20 22:21:14.026 screenresolution[1637:7404] Display 0: 1600x1200x32@60 22:21:14 INFO - 2016-07-20 22:21:14.032 screenresolution[1638:7406] starting screenresolution argv=screenresolution list 22:21:14 INFO - Available Modes on Display 0 22:21:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:21:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:21:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:21:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:21:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:21:14 INFO - Intel Iris: 22:21:14 INFO - Chipset Model: Intel Iris 22:21:14 INFO - Type: GPU 22:21:14 INFO - Bus: Built-In 22:21:14 INFO - VRAM (Dynamic, Max): 1536 MB 22:21:14 INFO - Vendor: Intel (0x8086) 22:21:14 INFO - Device ID: 0x0a2e 22:21:14 INFO - Revision ID: 0x0009 22:21:14 INFO - Displays: 22:21:14 INFO - Display: 22:21:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:21:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:21:14 INFO - Display Serial Number: 1600x1200 60 22:21:14 INFO - Main Display: Yes 22:21:14 INFO - Mirror: Off 22:21:14 INFO - Online: Yes 22:21:14 INFO - Rotation: Supported 22:21:14 INFO - Return code: 0 22:21:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:21:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:21:14 INFO - Python 2.7.3 22:21:14 INFO - Return code: 0 22:21:14 INFO - grabbing minidump binary from tooltool 22:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:14 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 0x104bfcd30>, '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 0x104961db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bf4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:21:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:21:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:21:14 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:21:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:21:14 INFO - Return code: 0 22:21:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:21:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:21:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:21:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:21:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:21:14 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/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0367', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:21:14 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/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0367 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:21:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ehGyWyl1K8/Render', 22:21:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:14 INFO - 'HOME': '/Users/cltbld', 22:21:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:14 INFO - 'LOGNAME': 'cltbld', 22:21:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:21:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:14 INFO - 'MOZ_NO_REMOTE': '1', 22:21:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:21:14 INFO - 'NO_EM_RESTART': '1', 22:21:14 INFO - 'PAGER': '/bin/cat', 22:21:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:14 INFO - 'PWD': '/builds/slave/test', 22:21:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:21:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:14 INFO - 'SHELL': '/bin/bash', 22:21:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnPkPdByg2/Listeners', 22:21:14 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 22:21:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:14 INFO - 'USER': 'cltbld', 22:21:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:14 INFO - 'XPC_FLAGS': '0x0', 22:21:14 INFO - 'XPC_SERVICE_NAME': '0', 22:21:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:14 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/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0367', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:21:14 INFO - mozversion application_buildid: 20160720195820 22:21:14 INFO - mozversion application_changeset: e25f1a122d9dc433d907ec2d1a6c9108cb3fbec0 22:21:14 INFO - mozversion application_display_name: Nightly 22:21:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:21:14 INFO - mozversion application_name: Firefox 22:21:14 INFO - mozversion application_remotingname: firefox 22:21:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:21:14 INFO - mozversion application_vendor: Mozilla 22:21:14 INFO - mozversion application_version: 50.0a1 22:21:14 INFO - mozversion platform_buildid: 20160720195820 22:21:14 INFO - mozversion platform_changeset: e25f1a122d9dc433d907ec2d1a6c9108cb3fbec0 22:21:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:21:14 INFO - mozversion platform_version: 50.0a1 22:21:14 INFO - using testdate: 1469078474 22:21:14 INFO - actual date: 1469078474 22:21:14 INFO - starting webserver on 'localhost:49271' 22:21:14 INFO - SUITE-START | Running 2 tests 22:21:14 INFO - TEST-START | dromaeo_css 22:21:14 INFO - operating with platform_type : mac_ 22:21:14 INFO - Initialising browser for dromaeo_css test... 22:21:14 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpMkCMf0/profile http://localhost:49271/getInfo.html) 22:21:16 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 22:21:16 INFO - PROCESS | 1648 | colorDepth:24 22:21:16 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 22:21:16 INFO - PROCESS | 1648 | __metrics 22:21:16 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:21:16 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:21:17 INFO - TEST-INFO | 1648: exit 0 22:21:17 INFO - Browser initialized. 22:21:17 INFO - Running cycle 1/1 for dromaeo_css test... 22:21:17 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpMkCMf0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:21:29 INFO - PROCESS | 1650 | RSS: Main: 245407744 22:21:29 INFO - PROCESS | 1650 | 22:22:21 INFO - PROCESS | 1650 | 1469078541029 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:24:32 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 22:24:34 INFO - PROCESS | 1650 | RSS: Main: 396169216 22:24:34 INFO - PROCESS | 1650 | 22:27:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 22:27:38 INFO - PROCESS | 1650 | RSS: Main: 381894656 22:27:38 INFO - PROCESS | 1650 | 22:30:40 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 22:30:40 INFO - PROCESS | 1650 | RSS: Main: 372367360 22:30:40 INFO - PROCESS | 1650 | 22:33:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 22:33:43 INFO - PROCESS | 1650 | RSS: Main: 370016256 22:33:43 INFO - PROCESS | 1650 | 22:36:47 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 22:36:47 INFO - PROCESS | 1650 | RSS: Main: 340672512 22:36:47 INFO - PROCESS | 1650 | 22:39:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 22:39:51 INFO - PROCESS | 1650 | RSS: Main: 415690752 22:39:51 INFO - PROCESS | 1650 | 22:39:51 INFO - PROCESS | 1650 | __start_tp_report 22:39:51 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 22:39:51 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 22:39:51 INFO - PROCESS | 1650 | |i|pagename|runs| 22:39:51 INFO - PROCESS | 1650 | |0;dojo.html;3378.628350881403;3397.235621985053;3399.5240666306736;3427.0061682112187;3482.3557641836264;5724.313082430104;5739.512141467975;5795;5827.388124246957;5837.657338812076;5773;5800.47795698386;5877.382874798149;5909.940900590993;5955.374685657997;5210.661307015051;5270.762815673284;5282.418933917266;5302.204669299597;5341.359036915575;5328.573714102882;5342;5354.089804733205;5356.11624082026;5376.1398176291805;5181.896362072775;5208.895822083575;5209.739513024371;5263.736813159327;5327.547158491513;4515.81936722534;5178;5250.6324557281005;5280.867978300535;5297.073012222884;5231.843044708627;5255.001549705582;5322.893542129135;5389.757460914268;5394.05604019299;5749.741261643236;5792.768289268424;5795.217645617872;5855.355910849803;5855.970720146414;6269.050045993604;6354.968225158891;6357.950938095209;6364.681765911732;6380.585261957958;6233.625982441068;6256.593321434092;6334.954732469137;6358.554901156921;6364.554481186319;5976.252968378953;6014.578979471395;6019.729112189962;6022.879542409128;6038.818835434944;6337.461315788118;6355.269144048401;6358.014507751306;6368.395002474757;6381.329960354189;6124.969375153185;6147.924113280158;6151.3541078187045;6197.938020619736;6217.409346112112;6346.524010699216;6365.745370185234;6383.521235907326;6398.9040164397575;6400.295967443578;6257.9687101564205;6297.275813281532;6315.96842015787;6322.272938612118;6328;6163.568550201532;6223;6232.688365581703;6236.844078898064;6335.239771227482;5017.071841709296;5032.849014529496;5036.143855544567;5040.521150490697;5043.5460808527405;4489.1021795641;4582.060677561092;4638.814447422065;4639.234526303189;4639.652026098056;5829.8251052468495;5832.212651292106;5841.707914604338;5848.590598658138;5862;6293.527985401113;6306.49548036156;6314.747410103636;6319.747210111636;6346.936530634727;5719.771209151588;5765;5778.5377169826315;5826.650400975955;5828.388019257917;5514;5551.722413879371;5552.361478430033;5597.076482380362;5603.327600687943;6369.617822930639;6406.775762848371;6421.1652485177565;6439.259485159267;6441.098246245623;6331.05034244867;6332.113504109337;6350.650714210763;6362.968185159045;6377.617342959437;6226.377362263737;6291.150694656163;6303.9684801575695;6315.179026726496;6323.304436511888;6239.251289845248;6315.936840631535;6324.936750632435;6347;6361.586496877688;6157.353477884758;6262.279837818527;6325.778597749057;6338.397852204033;6357.014662727284;6076.270847498328;6104.004449388181;6137.0399217141685;6185.505159587132;6226.750929962751;6167.414095660905;6225.408586184305;6229.221347331583;6231.1276421300145;6242.032484964838;6080.2399700037495;6093.268807743099;6098.984999550878;6107.206063211754;6111.388861113853;6094.481969032682;6110.315755985069;6144.754209831557;6192.907106393498;6220.688965551795;6032.034874420072;6057.545684073624;6064.545159112996;6071.089336599369;6114.6636934969 22:39:51 INFO - PROCESS | 1650 | |1;ext.html;423.91309781494795;448.815985445967;500.56951022120944;500.62703286051897;516.7158063065314;27727.722722772767;42287.57712422879;43053;43257.91855203618;45426.7728661357;44572.88567785807;44819;44854;44945.202191912365;44985;9777.804443911118;9957.950210248939;9962.302638815287;9966.20270378368;9972;8648.005479369891;8851.070637583058;9786.461744604045;12066.155369124164;12141.878581214207;12196.390180490984;12233;12245.26528408294;12249.693757656085;12256.203346782475;12061;12129.696757581043;12132.211406258653;12138.878611213862;12165.817512737316;8663.39356245063;8878.556072196428;8895.4662720237;8998.685046023358;11674.299542027506;39764;42528;42553.57446425527;42609.360859587134;42709.78645106754;3546.4680297955256;3721.38597131473;3731.440283957401;3733.1040550268012;4223.66210703146;28097.85951070252;33212.83393583041;33617.327653447035;33647;33693;3223.919402014945;3308.139883630249;3367.1413789483527;3396.1339858336214;3399.1672040350027;502784;503511;505306;505460;508957;61522;61730;62174.689126554986;62234.688826555575;62308.376916230256;53811.19283210755;64677;64721.352786472475;64762;64905.02642460367;14849.703005940037;16718.749218761728;16963.406280780113;17164.141792910305;17189.828101718824;12022.398880055962;13291.601251962456;13344.06591538583;13344.266065366499;13371.799423008662;15094;16741.83258167403;16879;17083.572910677332;17148.571285717957;6497.675116244169;6633.900491492632;6658.5671931324305;6701.865962680719;6708.865822683519;911.2436677557744;986.3243678080538;1054.620336678795;1062.9893701063045;1082.1829518713478;30622.69377306243;30684;30706.69293307083;31401.214969625944;31435.371292574022;1040.2718097331897;1081.9026287633994;1098.730810951306;1112.9165312601588;1139.5270962550449;12601.558945436866;15832.445864394691;15903.045817251003;15932.840671593136;16361.936474129381;188855;189169.05415472834;190061.0496947534;190066;934345;55379.72310138504;60737;61651.38348616546;61700;66252.668736656;1369.9999000729524;1385.8198434203691;1416.4121889415776;1421.3959067396315;1421.8080559124592;31337.529937050505;32171.67828321687;32237;32994.835025824716;33060.834695826365;52234.95530089301;58879.70560147172;60359.698201510466;60393.698031509564;63765.681171593846;608390;618191;620484;621527;621707;26408.339791505365;26801.463970720866;26884.865575671996;26886.865565672048;26935.191944241706;48492.272615910086;48775;48998.510014899395;49806;50035.74982125066;40196.598034019284;40400.3939940907;41208.41414292179;42185;42368.36447453229;33349.666503335626;39028;40272.597274026884;40509.96175286924;41996.950076247114;38799.80600096982;39675.60324396719;40330.596694033855;40411.393829092;42515.5748442524;29761.107166784164;32799.67200327966;35599;36718.082047949014;37039.25921481609;21842.890785546606;25929.870350648125;26172.73827261703;26330.078447254255;26516.337091572866 22:39:51 INFO - PROCESS | 1650 | |2;jquery.html;351.5851295471343;367.18484963381286;373.9962600373996;379.32669511616876;399.43479975834197;14198.858011419883;15331.386744530218;18091.4572562823;21157.471063223387;21694.566108677816;21679.241226557035;22012.559748805055;22243.11027558896;22757.77242227577;23644;6615.768448104317;6735.8989615155815;6962.2341542430295;7054.047703560005;7123.964380178092;6773.491988100887;6935.306469353075;7050.5769653820935;7062.258462861402;7076.7169313227405;6996.055532503124;6997.755078572251;7061.223265440797;7061.89407158893;7097.751578694747;5967.970160149215;6865.759698410557;7004.2645522220455;7027.156741191038;7075.363217310415;6504.1869766279215;6852.6231057291825;6856.177258728984;6857.314268573103;6864.553804002724;28779.28051798701;28963.275918102005;29373.11880643563;29701.851490742625;29765;1999.7000449932627;2448.179859746987;2519.031967738892;2907.912762617125;2972.346083861547;17265.686016028052;17558.736618950727;17728.734068988975;18164.545886352815;18171.273149074023;1962.7154062660834;2053.969190462144;2085.3326935380683;2120.1837292642444;2124.341454149219;111559;148174;150846;151016;153131;16930.576735581464;21022.684659730116;21251.0437030334;21620.783792161877;21695.674564881538;34393.31213375719;35445.82277088598;35623;35685;35690.821545892104;10988.780224395627;11179.83230251547;11248.662540123809;11292.266002709797;11485.598004069818;7721.146924738023;9165.31260155491;9189.770255743526;9218.492982885871;9388.577514011886;7691.923130761697;9383.953080234554;9525.714228573153;10000.749981250527;10005.19958403326;2924.295027099967;3274.672532746754;3375.8649654013566;3768.1333293342686;3773;851.5103815306223;906.8911730592239;914.9405288656216;926.2728757925131;948.4119845695575;21196;21789.910504474712;21814.90925453721;21857.781422185573;21888.671669924963;937.929655275857;938.1978408460668;960.8030353777459;965.8985806490358;966.6809952715587;9852.17243550655;10966.122710183166;11350.829737553944;11383.14626403023;11385.544578216779;519444;526195;527148;560635.1968240133;566305;145064.27467862805;151785.12027406145;156545;156812;157729;971.5870754929127;977.7995510920244;1014.2393205096178;1015.9644412445529;1019.4800651667703;19606.60786784285;19785.604287913873;19798.307059252857;19870.801291986896;19898;52516;57567;58398;59418.40581594129;60459.69770151121;61129.08306375319;87087.69368459351;87530.68703969318;87785.68321475311;87889.56055219939;5516.310461192351;7061.187963384276;7509.136449308399;7813.02337207849;8002.279794818494;8782;12901.677458063623;13263.801042984556;13382.93308533451;14064;10712.250142489951;11653;12138.271703697807;12144.33206173669;12190.963768079813;10352.171826254033;11595.18833681668;11751.647450576496;11888.58389956382;12137.393130343271;11838.763224735629;12141.392930353624;12199.69500762488;12261.816072759095;12267.815982760087;7735.18780528059;7873.881891771743;8005.1594582568;8009.279164875189;8175.427720059539;8012.839743204987;11002.284851484628;11517.82723259135;11532.711682208012;12055.57805476804 22:39:51 INFO - PROCESS | 1650 | |3;mootools.html;1391.3669280477384;1501.5120085972062;1527.3432424057655;1537.1315206908096;1580.9881675727534;7886.960565197181;7951.4831535950125;8838.381313308071;8980.73057808266;8997.730068097968;8907.064758200393;8970.910290897089;8982.889021080056;8992.730218093468;8995.91004089959;2036.389083275019;2308.9307320780363;2336.754640762721;2340.508493216427;2342.590046741822;2118.9046492907855;2303.7581053989343;2358.292512246328;2364.929052128439;2381.690380250565;2278.464560828204;2307.8615283082986;2313.0169677886915;2316.0388438797877;2316.3166865774638;2016.727741754859;2096.90563924623;2204.0853045985896;2315.80315673168;2317.2816426907543;2244.607193741089;2298.310506847939;2308.0652335804025;2316.212487754172;2320.640300753369;8926.285897128215;9004.459732416077;9011.909880901238;9017.774555636095;9067.365284430092;5143.8456846294675;5309;5310;5315.255864179018;5321.840344789663;3037.665856755755;3355.4295769719206;3485.250671105699;3557.1640664443835;3568.1971556399603;939.7697564096771;1048.7640280936805;1086.559943222996;1101.8071837428417;1104.7403860092872;11881.287122772575;11924.986376158036;11926.940365298118;11927.821082683766;11932.164748467698;4376.409184760017;4496.977515112403;4666.65000124992;4678.649101317346;4700.435947686299;10057.547410366551;10068.345557538736;10072.992700730016;10076.09315161639;10083.899161008443;5305.708186049727;5306.09796334624;5314.202869569518;5318.282031925718;5322.574194064466;3765.224849895846;4674.415698037746;4880.926786098212;4881.999190166008;4882.048000639841;3985.1830374773103;3988.541317748438;4054.412110243998;4075.286824805647;4129.2773764591075;1274.5411651805343;1275.853276873153;1279.4050766393486;1287.2662582327982;1288.890444312225;894.1148263219496;920.2408013388981;928.2944961829058;946.3470205558143;952.8094381123803;4941.703497790072;5740.655560666373;5846.912296315559;5988.640681559121;6111.236095488064;380.2281368821297;391.77668728824864;394.5029263128479;401.582354351477;410.1591736939263;3900.902477438087;3932.8791294480934;3935.2326296371934;4049.493813273341;4108.50697916246;10684.893151068545;10987.61543345979;11116.61091861781;11130.610428634958;11165.385903775208;11066.944665276622;11079.446027698583;11087.556497740161;11100.555977760961;11109.444527773741;8168.550729709804;8255.42212045151;8264.793380165544;8268.875966860502;8281.710140145187;5696.1455781633085;5772.19189313488;5941.494972927349;5952.404759524186;5961.821145365646;8689.044205137556;10298.948505257726;10327.225458090823;10398.844017339594;10430.58277668915;10547.736306592089;10554.261201715806;10562.049415552638;10570.947145264225;10595.311304765166;6956.756513522002;7208.243134471015;7433.553986760811;7664.770056898302;7724.729634462767;8408.747737567883;8763.737087887373;8767.649294028168;8778.956105219433;8781.956090219508;6712.563683360566;7597.582132982739;7754.651040703295;7766.728164514215;7875.8818617719635;7509.699612015458;7575.545467271982;7659.693612255447;7697.114831794192;7767.961160194162;7129.607871567114;7283.781486555412;7288.416926645961;7298.56208627484;7299.6350182489605;4862.513748625109;4863.221884498464;4995.825146119869;5063.5695965843315;5131.974340128276;4930.753462326941;5006.4242612098615;5032.647714659939;5051.570616497639;5243.764030618555 22:39:51 INFO - PROCESS | 1650 | |4;prototype.html;302.13589135073687;306.598356153439;315.15224047312734;316.62954343418215;318.67654330854174;5442.619016668835;5461.617686761923;5481.57401623985;5498.917516237259;5508.862278443046;5042.495750424956;5073.23901414788;5241.737913104349;5398.16328469087;5405.324379421992;3755.896697849213;4082.326416141333;4123.3196522573735;4142.730722503043;4169;3585.354636165489;4131;4154.480689913761;4155.522114956773;4167.166566686659;3465.1239471238937;3864.787436690981;4068.938965915499;4096.549379568245;4132.917341653165;3619.3304238715923;4092.6725861930972;4093.3246014407737;4099.631033207026;4099.97950010251;3160.6567208936112;3336.899893003214;3545.663161999606;3554.48459973305;3554.6267641897743;4270.722403043792;4928.6303527235605;4944.6785958912915;5218.530332270114;5262.447443018466;4304.39738436616;4666.32338310943;4700.083483720676;4710.835120770791;4711.740854253015;5650.446127315021;5697.259356283698;5702.344230413514;5708.942910570924;5717.170505438261;3889.552701439314;3895.941560876589;3902.453656488094;3912.317703387542;3916.3733802591732;4502.941808674958;5461.15352120422;5689.687067211302;5916.615419997686;5979.312379076375;3172.428962786674;3813.1420430402936;3921.3333733264844;3942.3692209246383;3969.146633473817;3963.900902477461;3978.4430179774854;3981;3993.983038565506;3995.4606128172904;3960.1287716702855;4358.063016451478;4385.815829725956;4465.843695470708;4655.813767449264;4770.76146192689;5718.513926316226;5747.3103227612955;5775;5784.045632470679;1287.4528325461642;1336.7460182565283;1365.1877133105786;1365.6312795545346;1367.2070199284392;783.1659282863732;841.8442588121217;867.4881819726265;870.7257213977576;876.5529579914162;4520.638348932078;4715.89176543512;5220.582353411718;5394.541463975527;5402.567794576425;4990.6257030722845;5592.573893657172;5797.217375654317;5918.526517878646;5933;4186.853460128881;4455.910881782346;5239.895202095937;5241.895162096736;5285.524302812765;2579.4841031793717;2629.907953221628;2896.797224194286;3051.5117581187346;3071.969280307213;4627.398438203012;4870.537298956593;5772.278465191851;5991.640501569833;6062.272527296753;5517.613767036269;5945.227120474397;5991.64050156992;6032.909506357408;6071.694585664103;5310.814121505806;5792.410324037324;5955.851103722442;6002.319350581844;6057.848553786191;5188.9740551297;5637.351704554028;5814.56940405163;5841.970790146022;5885.61743486672;5328.733563321741;5557.304707216916;5803.39064398249;5817.7963771267805;5835.56233282497;4454.26504626733;5656;6065.60573562717;6077.696115194134;6082.270127584541;3431.279431319451;3578.481120237499;3627.8572249741246;3847.4414514347645;3881.4954055972544;5227.849873027928;5370.824815949547;5849.660135945655;5912.42809377922;5926.436988486087;4809.711417314972;5153.974230128825;5270.8945821084135;5407.270018547446;5767.798127065533;4874.8537543873745;5787.450192231731;5792.727109183298;5792.94207057924;5823.961613435264;4919.926201107058;5397.325334333208;5619.353774316006;5633.845061762411;5724.2809665408595;4752.954350042985;5652.523697925828;5726.997775389207;5768.634859707499;5791.942080579141;5558.443116166447;5569.443055694398;5577.247071645276;5580.86047848807;5584.609077364546 22:39:51 INFO - PROCESS | 1650 | |5;yui.html;667.4493542827169;746.8058304840741;770.9267619576138;854.3891117850735;856.9528675922821;15353.078815271077;15915.681686366266;15937.681246375065;15943.441979530719;16053;14512.782308265385;14667.633309167277;14699.559013229593;14717.779233311509;14723.705525889476;713.4399496395331;737.1301863800713;770.7572114783853;797.9626485568766;884.9601767920443;887.1616322575169;895.194325107402;895.2032690905099;896.1531352871539;896.4621227263655;973.1484950668165;974.2108891797667;974.7855471796194;977.3891317926297;977.9902200977971;962.0475729028282;962.6630679262237;963.1476143612883;964.9530259668269;965.4159233663603;555.8304717061295;686.9759558415432;713.6717110129347;714.8748968930469;717.9856402871914;14662.41350345985;14793.852061479463;14799.334029968677;14800.555983320517;14803.703925921422;13739.24434156131;14005.5098071567;14191.858081419157;14192.290385480788;14370.712585748226;12843;12909;12934.741305173844;12989.740205195938;13284.933575332157;4638.443386793572;4814.15752243356;4819.6867203632;4821.276808478721;4865.951340486586;105411;106620;106721.46639266831;110944.44527777309;111412.4429377856;11014.339139651647;11216.270942388719;11219.439028048564;11449;11741.765164696659;21848;22332.665010024863;22369.88815055914;22376;22377.664335034988;7894.289513943772;7933.722319718782;7965.641546130438;8004.51972881629;8161.5919204039565;4385.7149285296355;4403.933940990888;4406.162829062468;4470.307102399133;4596.8161273548685;3327.9500807487907;3391.423458012144;3399.184195792992;3431.9828400858337;3434.690877821008;531.9440909794056;611.8776244751068;613.2702084519408;615.722924683894;625.337142628815;517.0202466326291;519.1874716069415;582.8105865593699;599.0983569727532;651.6513665189195;7506.549607023596;8357.58212089393;8598.011228708654;8701.825963480695;8763.693270735494;534.5670007294026;604.8900268008166;606.236142460503;610.5939550199157;646.5732616473106;4160.708750387505;4282.379055037064;4292.48490181174;4358.411614432074;4363.331654105745;104001;140908.2954585241;143244;144329.27835360757;144643.2767836175;102523;118466;121153;121180;121881.29652869178;79246;108478;109597.45201274102;109888;110897.89102108876;8249.752507424786;8282.006159260927;8285.54429506383;8390.664373424994;8477.522398140616;61969.69015154895;63570.68214658897;64068.3593164081;64182.679086604265;65091.698166035465;86443.13556864603;89048;91135.54432227796;91868.54065729628;94882.10235795108;11501.2524185931;11503.367314797766;11633.709157270798;11716;12252.142350035412;12249.87750122523;12280.508779649072;12363.69090772738;12451.066170037102;12994.35028248565;11695.76608467843;12261.509539618672;12266.32535210572;12312.076594255288;12388.318642474751;10865.891341086488;13203.801942971055;13745.862541374458;13747.725045499234;14074;13415.932920335727;13787.103838250481;13939.721205576034;14217.786733199217;14413.567592972227;10700.518476668412;10962;10964.451777410937;11105.333679979227;11769.234999724991;11262.436878155895;11340.20618556693;11373;11373.260738052331;11592.246503977214 22:39:51 INFO - PROCESS | 1650 | __end_tp_report 22:39:51 INFO - PROCESS | 1650 | __start_cc_report 22:39:51 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1503 22:39:51 INFO - PROCESS | 1650 | __end_cc_report 22:39:51 INFO - PROCESS | 1650 | __startTimestamp1469079591459__endTimestamp 22:39:51 INFO - PROCESS | 1650 | 22:39:51 INFO - PROCESS | 1650 | ------- Summary: start ------- 22:39:51 INFO - PROCESS | 1650 | Number of tests: 6 22:39:51 INFO - PROCESS | 1650 | 22:39:51 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5841.66 Median:6094.48 stddev:640.81 (10.5%) stddev-sans-first:612.96 22:39:51 INFO - PROCESS | 1650 | Values: 3378.6 3397.2 3399.5 3427.0 3482.4 5724.3 5739.5 5795.0 5827.4 5837.7 5773.0 5800.5 5877.4 5909.9 5955.4 5210.7 5270.8 5282.4 5302.2 5341.4 5328.6 5342.0 5354.1 5356.1 5376.1 5181.9 5208.9 5209.7 5263.7 5327.5 4515.8 5178.0 5250.6 5280.9 5297.1 5231.8 5255.0 5322.9 5389.8 5394.1 5749.7 5792.8 5795.2 5855.4 5856.0 6269.1 6355.0 6358.0 6364.7 6380.6 6233.6 6256.6 6335.0 6358.6 6364.6 5976.3 6014.6 6019.7 6022.9 6038.8 6337.5 6355.3 6358.0 6368.4 6381.3 6125.0 6147.9 6151.4 6197.9 6217.4 6346.5 6365.7 6383.5 6398.9 6400.3 6258.0 6297.3 6316.0 6322.3 6328.0 6163.6 6223.0 6232.7 6236.8 6335.2 5017.1 5032.8 5036.1 5040.5 5043.5 4489.1 4582.1 4638.8 4639.2 4639.7 5829.8 5832.2 5841.7 5848.6 5862.0 6293.5 6306.5 6314.7 6319.7 6346.9 5719.8 5765.0 5778.5 5826.7 5828.4 5514.0 5551.7 5552.4 5597.1 5603.3 6369.6 6406.8 6421.2 6439.3 6441.1 6331.1 6332.1 6350.7 6363.0 6377.6 6226.4 6291.2 6304.0 6315.2 6323.3 6239.3 6315.9 6324.9 6347.0 6361.6 6157.4 6262.3 6325.8 6338.4 6357.0 6076.3 6104.0 6137.0 6185.5 6226.8 6167.4 6225.4 6229.2 6231.1 6242.0 6080.2 6093.3 6099.0 6107.2 6111.4 6094.5 6110.3 6144.8 6192.9 6220.7 6032.0 6057.5 6064.5 6071.1 6114.7 22:39:51 INFO - PROCESS | 1650 | 22:39:51 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:64426.25 Median:21266.46 stddev:142424.39 (669.7%) stddev-sans-first:142742.87 22:39:51 INFO - PROCESS | 1650 | Values: 423.9 448.8 500.6 500.6 516.7 27727.7 42287.6 43053.0 43257.9 45426.8 44572.9 44819.0 44854.0 44945.2 44985.0 9777.8 9958.0 9962.3 9966.2 9972.0 8648.0 8851.1 9786.5 12066.2 12141.9 12196.4 12233.0 12245.3 12249.7 12256.2 12061.0 12129.7 12132.2 12138.9 12165.8 8663.4 8878.6 8895.5 8998.7 11674.3 39764.0 42528.0 42553.6 42609.4 42709.8 3546.5 3721.4 3731.4 3733.1 4223.7 28097.9 33212.8 33617.3 33647.0 33693.0 3223.9 3308.1 3367.1 3396.1 3399.2 502784.0 503511.0 505306.0 505460.0 508957.0 61522.0 61730.0 62174.7 62234.7 62308.4 53811.2 64677.0 64721.4 64762.0 64905.0 14849.7 16718.7 16963.4 17164.1 17189.8 12022.4 13291.6 13344.1 13344.3 13371.8 15094.0 16741.8 16879.0 17083.6 17148.6 6497.7 6633.9 6658.6 6701.9 6708.9 911.2 986.3 1054.6 1063.0 1082.2 30622.7 30684.0 30706.7 31401.2 31435.4 1040.3 1081.9 1098.7 1112.9 1139.5 12601.6 15832.4 15903.0 15932.8 16361.9 188855.0 189169.1 190061.0 190066.0 934345.0 55379.7 60737.0 61651.4 61700.0 66252.7 1370.0 1385.8 1416.4 1421.4 1421.8 31337.5 32171.7 32237.0 32994.8 33060.8 52235.0 58879.7 60359.7 60393.7 63765.7 608390.0 618191.0 620484.0 621527.0 621707.0 26408.3 26801.5 26884.9 26886.9 26935.2 48492.3 48775.0 48998.5 49806.0 50035.7 40196.6 40400.4 41208.4 42185.0 42368.4 33349.7 39028.0 40272.6 40510.0 41997.0 38799.8 39675.6 40330.6 40411.4 42515.6 29761.1 32799.7 35599.0 36718.1 37039.3 21842.9 25929.9 26172.7 26330.1 26516.3 22:39:51 INFO - PROCESS | 1650 | 22:39:51 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:36712.27 Median:16337.10 stddev:92143.69 (564.0%) stddev-sans-first:92361.74 22:39:51 INFO - PROCESS | 1650 | Values: 351.6 367.2 374.0 379.3 399.4 14198.9 15331.4 18091.5 21157.5 21694.6 21679.2 22012.6 22243.1 22757.8 23644.0 6615.8 6735.9 6962.2 7054.0 7124.0 6773.5 6935.3 7050.6 7062.3 7076.7 6996.1 6997.8 7061.2 7061.9 7097.8 5968.0 6865.8 7004.3 7027.2 7075.4 6504.2 6852.6 6856.2 6857.3 6864.6 28779.3 28963.3 29373.1 29701.9 29765.0 1999.7 2448.2 2519.0 2907.9 2972.3 17265.7 17558.7 17728.7 18164.5 18171.3 1962.7 2054.0 2085.3 2120.2 2124.3 111559.0 148174.0 150846.0 151016.0 153131.0 16930.6 21022.7 21251.0 21620.8 21695.7 34393.3 35445.8 35623.0 35685.0 35690.8 10988.8 11179.8 11248.7 11292.3 11485.6 7721.1 9165.3 9189.8 9218.5 9388.6 7691.9 9384.0 9525.7 10000.7 10005.2 2924.3 3274.7 3375.9 3768.1 3773.0 851.5 906.9 914.9 926.3 948.4 21196.0 21789.9 21814.9 21857.8 21888.7 937.9 938.2 960.8 965.9 966.7 9852.2 10966.1 11350.8 11383.1 11385.5 519444.0 526195.0 527148.0 560635.2 566305.0 145064.3 151785.1 156545.0 156812.0 157729.0 971.6 977.8 1014.2 1016.0 1019.5 19606.6 19785.6 19798.3 19870.8 19898.0 52516.0 57567.0 58398.0 59418.4 60459.7 61129.1 87087.7 87530.7 87785.7 87889.6 5516.3 7061.2 7509.1 7813.0 8002.3 8782.0 12901.7 13263.8 13382.9 14064.0 10712.3 11653.0 12138.3 12144.3 12191.0 10352.2 11595.2 11751.6 11888.6 12137.4 11838.8 12141.4 12199.7 12261.8 12267.8 7735.2 7873.9 8005.2 8009.3 8175.4 8012.8 11002.3 11517.8 11532.7 12055.6 22:39:51 INFO - PROCESS | 1650 | 22:39:51 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5760.63 Median:4581.81 stddev:3340.30 (72.9%) stddev-sans-first:3333.53 22:39:51 INFO - PROCESS | 1650 | Values: 1391.4 1501.5 1527.3 1537.1 1581.0 7887.0 7951.5 8838.4 8980.7 8997.7 8907.1 8970.9 8982.9 8992.7 8995.9 2036.4 2308.9 2336.8 2340.5 2342.6 2118.9 2303.8 2358.3 2364.9 2381.7 2278.5 2307.9 2313.0 2316.0 2316.3 2016.7 2096.9 2204.1 2315.8 2317.3 2244.6 2298.3 2308.1 2316.2 2320.6 8926.3 9004.5 9011.9 9017.8 9067.4 5143.8 5309.0 5310.0 5315.3 5321.8 3037.7 3355.4 3485.3 3557.2 3568.2 939.8 1048.8 1086.6 1101.8 1104.7 11881.3 11925.0 11926.9 11927.8 11932.2 4376.4 4497.0 4666.7 4678.6 4700.4 10057.5 10068.3 10073.0 10076.1 10083.9 5305.7 5306.1 5314.2 5318.3 5322.6 3765.2 4674.4 4880.9 4882.0 4882.0 3985.2 3988.5 4054.4 4075.3 4129.3 1274.5 1275.9 1279.4 1287.3 1288.9 894.1 920.2 928.3 946.3 952.8 4941.7 5740.7 5846.9 5988.6 6111.2 380.2 391.8 394.5 401.6 410.2 3900.9 3932.9 3935.2 4049.5 4108.5 10684.9 10987.6 11116.6 11130.6 11165.4 11066.9 11079.4 11087.6 11100.6 11109.4 8168.6 8255.4 8264.8 8268.9 8281.7 5696.1 5772.2 5941.5 5952.4 5961.8 8689.0 10298.9 10327.2 10398.8 10430.6 10547.7 10554.3 10562.0 10570.9 10595.3 6956.8 7208.2 7433.6 7664.8 7724.7 8408.7 8763.7 8767.6 8779.0 8782.0 6712.6 7597.6 7754.7 7766.7 7875.9 7509.7 7575.5 7659.7 7697.1 7768.0 7129.6 7283.8 7288.4 7298.6 7299.6 4862.5 4863.2 4995.8 5063.6 5132.0 4930.8 5006.4 5032.6 5051.6 5243.8 22:39:51 INFO - PROCESS | 1650 | 22:39:51 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4567.28 Median:5203.75 stddev:1424.04 (27.4%) stddev-sans-first:1391.59 22:39:51 INFO - PROCESS | 1650 | Values: 302.1 306.6 315.2 316.6 318.7 5442.6 5461.6 5481.6 5498.9 5508.9 5042.5 5073.2 5241.7 5398.2 5405.3 3755.9 4082.3 4123.3 4142.7 4169.0 3585.4 4131.0 4154.5 4155.5 4167.2 3465.1 3864.8 4068.9 4096.5 4132.9 3619.3 4092.7 4093.3 4099.6 4100.0 3160.7 3336.9 3545.7 3554.5 3554.6 4270.7 4928.6 4944.7 5218.5 5262.4 4304.4 4666.3 4700.1 4710.8 4711.7 5650.4 5697.3 5702.3 5708.9 5717.2 3889.6 3895.9 3902.5 3912.3 3916.4 4502.9 5461.2 5689.7 5916.6 5979.3 3172.4 3813.1 3921.3 3942.4 3969.1 3963.9 3978.4 3981.0 3994.0 3995.5 3960.1 4358.1 4385.8 4465.8 4655.8 4770.8 5718.5 5747.3 5775.0 5784.0 1287.5 1336.7 1365.2 1365.6 1367.2 783.2 841.8 867.5 870.7 876.6 4520.6 4715.9 5220.6 5394.5 5402.6 4990.6 5592.6 5797.2 5918.5 5933.0 4186.9 4455.9 5239.9 5241.9 5285.5 2579.5 2629.9 2896.8 3051.5 3072.0 4627.4 4870.5 5772.3 5991.6 6062.3 5517.6 5945.2 5991.6 6032.9 6071.7 5310.8 5792.4 5955.9 6002.3 6057.8 5189.0 5637.4 5814.6 5842.0 5885.6 5328.7 5557.3 5803.4 5817.8 5835.6 4454.3 5656.0 6065.6 6077.7 6082.3 3431.3 3578.5 3627.9 3847.4 3881.5 5227.8 5370.8 5849.7 5912.4 5926.4 4809.7 5154.0 5270.9 5407.3 5767.8 4874.9 5787.5 5792.7 5792.9 5824.0 4919.9 5397.3 5619.4 5633.8 5724.3 4753.0 5652.5 5727.0 5768.6 5791.9 5558.4 5569.4 5577.2 5580.9 5584.6 22:39:51 INFO - PROCESS | 1650 | 22:39:51 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:23865.47 Median:3395.30 stddev:37282.40 (1098.1%) stddev-sans-first:37346.30 22:39:51 INFO - PROCESS | 1650 | Values: 667.4 746.8 770.9 854.4 857.0 15353.1 15915.7 15937.7 15943.4 16053.0 14512.8 14667.6 14699.6 14717.8 14723.7 713.4 737.1 770.8 798.0 885.0 887.2 895.2 895.2 896.2 896.5 973.1 974.2 974.8 977.4 978.0 962.0 962.7 963.1 965.0 965.4 555.8 687.0 713.7 714.9 718.0 14662.4 14793.9 14799.3 14800.6 14803.7 13739.2 14005.5 14191.9 14192.3 14370.7 12843.0 12909.0 12934.7 12989.7 13284.9 4638.4 4814.2 4819.7 4821.3 4866.0 105411.0 106620.0 106721.5 110944.4 111412.4 11014.3 11216.3 11219.4 11449.0 11741.8 21848.0 22332.7 22369.9 22376.0 22377.7 7894.3 7933.7 7965.6 8004.5 8161.6 4385.7 4403.9 4406.2 4470.3 4596.8 3328.0 3391.4 3399.2 3432.0 3434.7 531.9 611.9 613.3 615.7 625.3 517.0 519.2 582.8 599.1 651.7 7506.5 8357.6 8598.0 8701.8 8763.7 534.6 604.9 606.2 610.6 646.6 4160.7 4282.4 4292.5 4358.4 4363.3 104001.0 140908.3 143244.0 144329.3 144643.3 102523.0 118466.0 121153.0 121180.0 121881.3 79246.0 108478.0 109597.5 109888.0 110897.9 8249.8 8282.0 8285.5 8390.7 8477.5 61969.7 63570.7 64068.4 64182.7 65091.7 86443.1 89048.0 91135.5 91868.5 94882.1 11501.3 11503.4 11633.7 11716.0 12252.1 12249.9 12280.5 12363.7 12451.1 12994.4 11695.8 12261.5 12266.3 12312.1 12388.3 10865.9 13203.8 13745.9 13747.7 14074.0 13415.9 13787.1 13939.7 14217.8 14413.6 10700.5 10962.0 10964.5 11105.3 11769.2 11262.4 11340.2 11373.0 11373.3 11592.2 22:39:51 INFO - PROCESS | 1650 | -------- Summary: end -------- 22:39:51 INFO - PROCESS | 1650 | 22:39:52 INFO - TEST-INFO | 1650: exit 0 22:39:52 INFO - TEST-OK | dromaeo_css | took 1117441ms 22:39:52 INFO - TEST-START | kraken 22:39:52 INFO - operating with platform_type : mac_ 22:39:52 INFO - Initialising browser for kraken test... 22:39:52 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpJQZdJE/profile http://localhost:49271/getInfo.html) 22:39:54 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 22:39:54 INFO - PROCESS | 1661 | colorDepth:24 22:39:54 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 22:39:54 INFO - PROCESS | 1661 | __metrics 22:39:54 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:39:54 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:39:55 INFO - TEST-INFO | 1661: exit 0 22:39:55 INFO - Browser initialized. 22:39:55 INFO - Running cycle 1/1 for kraken test... 22:39:55 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpJQZdJE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:40:07 INFO - PROCESS | 1662 | RSS: Main: 245637120 22:40:07 INFO - PROCESS | 1662 | 22:40:09 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 22:40:10 INFO - PROCESS | 1662 | RSS: Main: 294674432 22:40:10 INFO - PROCESS | 1662 | 22:40:13 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 22:40:13 INFO - PROCESS | 1662 | RSS: Main: 305807360 22:40:13 INFO - PROCESS | 1662 | 22:40:17 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 22:40:17 INFO - PROCESS | 1662 | RSS: Main: 392425472 22:40:17 INFO - PROCESS | 1662 | 22:40:19 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 22:40:20 INFO - PROCESS | 1662 | RSS: Main: 294268928 22:40:20 INFO - PROCESS | 1662 | 22:40:22 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:40:23 INFO - PROCESS | 1662 | RSS: Main: 298123264 22:40:23 INFO - PROCESS | 1662 | 22:40:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 22:40:26 INFO - PROCESS | 1662 | RSS: Main: 334508032 22:40:26 INFO - PROCESS | 1662 | 22:40:29 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 22:40:30 INFO - PROCESS | 1662 | RSS: Main: 337707008 22:40:30 INFO - PROCESS | 1662 | 22:40:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 22:40:33 INFO - PROCESS | 1662 | RSS: Main: 333508608 22:40:33 INFO - PROCESS | 1662 | 22:40:35 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:40:35 INFO - PROCESS | 1662 | RSS: Main: 313843712 22:40:35 INFO - PROCESS | 1662 | 22:40:37 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:40:38 INFO - PROCESS | 1662 | RSS: Main: 320339968 22:40:38 INFO - PROCESS | 1662 | 22:40:40 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:40:41 INFO - PROCESS | 1662 | RSS: Main: 318160896 22:40:41 INFO - PROCESS | 1662 | 22:40:44 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:40:44 INFO - PROCESS | 1662 | RSS: Main: 326811648 22:40:44 INFO - PROCESS | 1662 | 22:40:47 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:40:48 INFO - PROCESS | 1662 | RSS: Main: 329674752 22:40:48 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 22:40:50 INFO - PROCESS | 1662 | RSS: Main: 372916224 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | __start_tp_report 22:40:50 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 22:40:50 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 22:40:50 INFO - PROCESS | 1662 | |i|pagename|runs| 22:40:50 INFO - PROCESS | 1662 | |0;ai-astar;102;113;113;114;114;113;113;112;111;113 22:40:50 INFO - PROCESS | 1662 | |1;audio-beat-detection;142;109;109;169;135;135;128;105;163;106 22:40:50 INFO - PROCESS | 1662 | |2;audio-dft;185;177;184;182;184;180;181;183;181;183 22:40:50 INFO - PROCESS | 1662 | |3;audio-fft;80;73;58;88;58;58;65;61;83;60 22:40:50 INFO - PROCESS | 1662 | |4;audio-oscillator;86;85;95;72;77;68;95;69;69;102 22:40:50 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;88;85;88;85;87;84;88;85;88;85 22:40:50 INFO - PROCESS | 1662 | |6;imaging-darkroom;93;94;95;94;94;95;94;95;107;148 22:40:50 INFO - PROCESS | 1662 | |7;imaging-desaturate;85;86;84;85;94;122;86;84;86;85 22:40:50 INFO - PROCESS | 1662 | |8;json-parse-financial;54;52;53;51;51;54;51;53;52;51 22:40:50 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;67;75;84;78;72;72;72;72;72;73 22:40:50 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;84;86;86;84;84;86;83;99;104;98 22:40:50 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;139;140;138;144;158;150;135;136;226;133 22:40:50 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;223;173;167;177;167;166;168;169;165;169 22:40:50 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;67;67;68;67;66;67;68;71;82;85 22:40:50 INFO - PROCESS | 1662 | __end_tp_report 22:40:50 INFO - PROCESS | 1662 | __start_cc_report 22:40:50 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2126 22:40:50 INFO - PROCESS | 1662 | __end_cc_report 22:40:50 INFO - PROCESS | 1662 | __startTimestamp1469079650592__endTimestamp 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | ------- Summary: start ------- 22:40:50 INFO - PROCESS | 1662 | Number of tests: 14 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.55 (3.1%) stddev-sans-first:0.93 22:40:50 INFO - PROCESS | 1662 | Values: 102.0 113.0 113.0 114.0 114.0 113.0 113.0 112.0 111.0 113.0 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:130.10 Median:135.00 stddev:23.31 (17.3%) stddev-sans-first:24.32 22:40:50 INFO - PROCESS | 1662 | Values: 142.0 109.0 109.0 169.0 135.0 135.0 128.0 105.0 163.0 106.0 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:182.00 Median:183.00 stddev:2.36 (1.3%) stddev-sans-first:2.24 22:40:50 INFO - PROCESS | 1662 | Values: 185.0 177.0 184.0 182.0 184.0 180.0 181.0 183.0 181.0 183.0 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:68.40 Median:69.00 stddev:11.62 (16.8%) stddev-sans-first:11.54 22:40:50 INFO - PROCESS | 1662 | Values: 80.0 73.0 58.0 88.0 58.0 58.0 65.0 61.0 83.0 60.0 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:81.80 Median:81.00 stddev:12.57 (15.5%) stddev-sans-first:13.24 22:40:50 INFO - PROCESS | 1662 | Values: 86.0 85.0 95.0 72.0 77.0 68.0 95.0 69.0 69.0 102.0 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.50 stddev:1.64 (1.9%) stddev-sans-first:1.62 22:40:50 INFO - PROCESS | 1662 | Values: 88.0 85.0 88.0 85.0 87.0 84.0 88.0 85.0 88.0 85.0 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:100.90 Median:94.00 stddev:17.04 (18.1%) stddev-sans-first:17.83 22:40:50 INFO - PROCESS | 1662 | Values: 93.0 94.0 95.0 94.0 94.0 95.0 94.0 95.0 107.0 148.0 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:89.70 Median:85.50 stddev:11.71 (13.7%) stddev-sans-first:12.30 22:40:50 INFO - PROCESS | 1662 | Values: 85.0 86.0 84.0 85.0 94.0 122.0 86.0 84.0 86.0 85.0 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.50 stddev:1.23 (2.3%) stddev-sans-first:1.12 22:40:50 INFO - PROCESS | 1662 | Values: 54.0 52.0 53.0 51.0 51.0 54.0 51.0 53.0 52.0 51.0 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:73.70 Median:72.50 stddev:4.55 (6.3%) stddev-sans-first:4.13 22:40:50 INFO - PROCESS | 1662 | Values: 67.0 75.0 84.0 78.0 72.0 72.0 72.0 72.0 72.0 73.0 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:86.00 stddev:7.76 (9.0%) stddev-sans-first:7.98 22:40:50 INFO - PROCESS | 1662 | Values: 84.0 86.0 86.0 84.0 84.0 86.0 83.0 99.0 104.0 98.0 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:142.00 stddev:27.79 (19.6%) stddev-sans-first:29.20 22:40:50 INFO - PROCESS | 1662 | Values: 139.0 140.0 138.0 144.0 158.0 150.0 135.0 136.0 226.0 133.0 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.40 Median:169.00 stddev:17.44 (10.3%) stddev-sans-first:3.77 22:40:50 INFO - PROCESS | 1662 | Values: 223.0 173.0 167.0 177.0 167.0 166.0 168.0 169.0 165.0 169.0 22:40:50 INFO - PROCESS | 1662 | 22:40:50 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:68.00 stddev:6.86 (10.1%) stddev-sans-first:7.14 22:40:50 INFO - PROCESS | 1662 | Values: 67.0 67.0 68.0 67.0 66.0 67.0 68.0 71.0 82.0 85.0 22:40:50 INFO - PROCESS | 1662 | -------- Summary: end -------- 22:40:50 INFO - PROCESS | 1662 | 22:40:51 INFO - TEST-INFO | 1662: exit 0 22:40:51 INFO - TEST-OK | kraken | took 59414ms 22:40:51 INFO - SUITE-END | took 1176s 22:40:51 INFO - Completed test suite (00:19:37) 22:40:51 INFO - javascript benchmark 22:40:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3378.628350881403, 3397.235621985053, 3399.5240666306736, 3427.0061682112187, 3482.3557641836264, 5724.313082430104, 5739.512141467975, 5795.0, 5827.388124246957, 5837.657338812076, 5773.0, 5800.47795698386, 5877.382874798149, 5909.940900590993, 5955.374685657997, 5210.661307015051, 5270.762815673284, 5282.418933917266, 5302.204669299597, 5341.359036915575, 5328.573714102882, 5342.0, 5354.089804733205, 5356.11624082026, 5376.1398176291805, 5181.896362072775, 5208.895822083575, 5209.739513024371, 5263.736813159327, 5327.547158491513, 4515.81936722534, 5178.0, 5250.6324557281005, 5280.867978300535, 5297.073012222884, 5231.843044708627, 5255.001549705582, 5322.893542129135, 5389.757460914268, 5394.05604019299, 5749.741261643236, 5792.768289268424, 5795.217645617872, 5855.355910849803, 5855.970720146414, 6269.050045993604, 6354.968225158891, 6357.950938095209, 6364.681765911732, 6380.585261957958, 6233.625982441068, 6256.593321434092, 6334.954732469137, 6358.554901156921, 6364.554481186319, 5976.252968378953, 6014.578979471395, 6019.729112189962, 6022.879542409128, 6038.818835434944, 6337.461315788118, 6355.269144048401, 6358.014507751306, 6368.395002474757, 6381.329960354189, 6124.969375153185, 6147.924113280158, 6151.3541078187045, 6197.938020619736, 6217.409346112112, 6346.524010699216, 6365.745370185234, 6383.521235907326, 6398.9040164397575, 6400.295967443578, 6257.9687101564205, 6297.275813281532, 6315.96842015787, 6322.272938612118, 6328.0, 6163.568550201532, 6223.0, 6232.688365581703, 6236.844078898064, 6335.239771227482, 5017.071841709296, 5032.849014529496, 5036.143855544567, 5040.521150490697, 5043.5460808527405, 4489.1021795641, 4582.060677561092, 4638.814447422065, 4639.234526303189, 4639.652026098056, 5829.8251052468495, 5832.212651292106, 5841.707914604338, 5848.590598658138, 5862.0, 6293.527985401113, 6306.49548036156, 6314.747410103636, 6319.747210111636, 6346.936530634727, 5719.771209151588, 5765.0, 5778.5377169826315, 5826.650400975955, 5828.388019257917, 5514.0, 5551.722413879371, 5552.361478430033, 5597.076482380362, 5603.327600687943, 6369.617822930639, 6406.775762848371, 6421.1652485177565, 6439.259485159267, 6441.098246245623, 6331.05034244867, 6332.113504109337, 6350.650714210763, 6362.968185159045, 6377.617342959437, 6226.377362263737, 6291.150694656163, 6303.9684801575695, 6315.179026726496, 6323.304436511888, 6239.251289845248, 6315.936840631535, 6324.936750632435, 6347.0, 6361.586496877688, 6157.353477884758, 6262.279837818527, 6325.778597749057, 6338.397852204033, 6357.014662727284, 6076.270847498328, 6104.004449388181, 6137.0399217141685, 6185.505159587132, 6226.750929962751, 6167.414095660905, 6225.408586184305, 6229.221347331583, 6231.1276421300145, 6242.032484964838, 6080.2399700037495, 6093.268807743099, 6098.984999550878, 6107.206063211754, 6111.388861113853, 6094.481969032682, 6110.315755985069, 6144.754209831557, 6192.907106393498, 6220.688965551795, 6032.034874420072, 6057.545684073624, 6064.545159112996, 6071.089336599369, 6114.6636934969], "value": 5800.281951910597, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.91309781494795, 448.815985445967, 500.56951022120944, 500.62703286051897, 516.7158063065314, 27727.722722772767, 42287.57712422879, 43053.0, 43257.91855203618, 45426.7728661357, 44572.88567785807, 44819.0, 44854.0, 44945.202191912365, 44985.0, 9777.804443911118, 9957.950210248939, 9962.302638815287, 9966.20270378368, 9972.0, 8648.005479369891, 8851.070637583058, 9786.461744604045, 12066.155369124164, 12141.878581214207, 12196.390180490984, 12233.0, 12245.26528408294, 12249.693757656085, 12256.203346782475, 12061.0, 12129.696757581043, 12132.211406258653, 12138.878611213862, 12165.817512737316, 8663.39356245063, 8878.556072196428, 8895.4662720237, 8998.685046023358, 11674.299542027506, 39764.0, 42528.0, 42553.57446425527, 42609.360859587134, 42709.78645106754, 3546.4680297955256, 3721.38597131473, 3731.440283957401, 3733.1040550268012, 4223.66210703146, 28097.85951070252, 33212.83393583041, 33617.327653447035, 33647.0, 33693.0, 3223.919402014945, 3308.139883630249, 3367.1413789483527, 3396.1339858336214, 3399.1672040350027, 502784.0, 503511.0, 505306.0, 505460.0, 508957.0, 61522.0, 61730.0, 62174.689126554986, 62234.688826555575, 62308.376916230256, 53811.19283210755, 64677.0, 64721.352786472475, 64762.0, 64905.02642460367, 14849.703005940037, 16718.749218761728, 16963.406280780113, 17164.141792910305, 17189.828101718824, 12022.398880055962, 13291.601251962456, 13344.06591538583, 13344.266065366499, 13371.799423008662, 15094.0, 16741.83258167403, 16879.0, 17083.572910677332, 17148.571285717957, 6497.675116244169, 6633.900491492632, 6658.5671931324305, 6701.865962680719, 6708.865822683519, 911.2436677557744, 986.3243678080538, 1054.620336678795, 1062.9893701063045, 1082.1829518713478, 30622.69377306243, 30684.0, 30706.69293307083, 31401.214969625944, 31435.371292574022, 1040.2718097331897, 1081.9026287633994, 1098.730810951306, 1112.9165312601588, 1139.5270962550449, 12601.558945436866, 15832.445864394691, 15903.045817251003, 15932.840671593136, 16361.936474129381, 188855.0, 189169.05415472834, 190061.0496947534, 190066.0, 934345.0, 55379.72310138504, 60737.0, 61651.38348616546, 61700.0, 66252.668736656, 1369.9999000729524, 1385.8198434203691, 1416.4121889415776, 1421.3959067396315, 1421.8080559124592, 31337.529937050505, 32171.67828321687, 32237.0, 32994.835025824716, 33060.834695826365, 52234.95530089301, 58879.70560147172, 60359.698201510466, 60393.698031509564, 63765.681171593846, 608390.0, 618191.0, 620484.0, 621527.0, 621707.0, 26408.339791505365, 26801.463970720866, 26884.865575671996, 26886.865565672048, 26935.191944241706, 48492.272615910086, 48775.0, 48998.510014899395, 49806.0, 50035.74982125066, 40196.598034019284, 40400.3939940907, 41208.41414292179, 42185.0, 42368.36447453229, 33349.666503335626, 39028.0, 40272.597274026884, 40509.96175286924, 41996.950076247114, 38799.80600096982, 39675.60324396719, 40330.596694033855, 40411.393829092, 42515.5748442524, 29761.107166784164, 32799.67200327966, 35599.0, 36718.082047949014, 37039.25921481609, 21842.890785546606, 25929.870350648125, 26172.73827261703, 26330.078447254255, 26516.337091572866], "value": 20384.554413029695, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.5851295471343, 367.18484963381286, 373.9962600373996, 379.32669511616876, 399.43479975834197, 14198.858011419883, 15331.386744530218, 18091.4572562823, 21157.471063223387, 21694.566108677816, 21679.241226557035, 22012.559748805055, 22243.11027558896, 22757.77242227577, 23644.0, 6615.768448104317, 6735.8989615155815, 6962.2341542430295, 7054.047703560005, 7123.964380178092, 6773.491988100887, 6935.306469353075, 7050.5769653820935, 7062.258462861402, 7076.7169313227405, 6996.055532503124, 6997.755078572251, 7061.223265440797, 7061.89407158893, 7097.751578694747, 5967.970160149215, 6865.759698410557, 7004.2645522220455, 7027.156741191038, 7075.363217310415, 6504.1869766279215, 6852.6231057291825, 6856.177258728984, 6857.314268573103, 6864.553804002724, 28779.28051798701, 28963.275918102005, 29373.11880643563, 29701.851490742625, 29765.0, 1999.7000449932627, 2448.179859746987, 2519.031967738892, 2907.912762617125, 2972.346083861547, 17265.686016028052, 17558.736618950727, 17728.734068988975, 18164.545886352815, 18171.273149074023, 1962.7154062660834, 2053.969190462144, 2085.3326935380683, 2120.1837292642444, 2124.341454149219, 111559.0, 148174.0, 150846.0, 151016.0, 153131.0, 16930.576735581464, 21022.684659730116, 21251.0437030334, 21620.783792161877, 21695.674564881538, 34393.31213375719, 35445.82277088598, 35623.0, 35685.0, 35690.821545892104, 10988.780224395627, 11179.83230251547, 11248.662540123809, 11292.266002709797, 11485.598004069818, 7721.146924738023, 9165.31260155491, 9189.770255743526, 9218.492982885871, 9388.577514011886, 7691.923130761697, 9383.953080234554, 9525.714228573153, 10000.749981250527, 10005.19958403326, 2924.295027099967, 3274.672532746754, 3375.8649654013566, 3768.1333293342686, 3773.0, 851.5103815306223, 906.8911730592239, 914.9405288656216, 926.2728757925131, 948.4119845695575, 21196.0, 21789.910504474712, 21814.90925453721, 21857.781422185573, 21888.671669924963, 937.929655275857, 938.1978408460668, 960.8030353777459, 965.8985806490358, 966.6809952715587, 9852.17243550655, 10966.122710183166, 11350.829737553944, 11383.14626403023, 11385.544578216779, 519444.0, 526195.0, 527148.0, 560635.1968240133, 566305.0, 145064.27467862805, 151785.12027406145, 156545.0, 156812.0, 157729.0, 971.5870754929127, 977.7995510920244, 1014.2393205096178, 1015.9644412445529, 1019.4800651667703, 19606.60786784285, 19785.604287913873, 19798.307059252857, 19870.801291986896, 19898.0, 52516.0, 57567.0, 58398.0, 59418.40581594129, 60459.69770151121, 61129.08306375319, 87087.69368459351, 87530.68703969318, 87785.68321475311, 87889.56055219939, 5516.310461192351, 7061.187963384276, 7509.136449308399, 7813.02337207849, 8002.279794818494, 8782.0, 12901.677458063623, 13263.801042984556, 13382.93308533451, 14064.0, 10712.250142489951, 11653.0, 12138.271703697807, 12144.33206173669, 12190.963768079813, 10352.171826254033, 11595.18833681668, 11751.647450576496, 11888.58389956382, 12137.393130343271, 11838.763224735629, 12141.392930353624, 12199.69500762488, 12261.816072759095, 12267.815982760087, 7735.18780528059, 7873.881891771743, 8005.1594582568, 8009.279164875189, 8175.427720059539, 8012.839743204987, 11002.284851484628, 11517.82723259135, 11532.711682208012, 12055.57805476804], "value": 11116.655187152965, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.3669280477384, 1501.5120085972062, 1527.3432424057655, 1537.1315206908096, 1580.9881675727534, 7886.960565197181, 7951.4831535950125, 8838.381313308071, 8980.73057808266, 8997.730068097968, 8907.064758200393, 8970.910290897089, 8982.889021080056, 8992.730218093468, 8995.91004089959, 2036.389083275019, 2308.9307320780363, 2336.754640762721, 2340.508493216427, 2342.590046741822, 2118.9046492907855, 2303.7581053989343, 2358.292512246328, 2364.929052128439, 2381.690380250565, 2278.464560828204, 2307.8615283082986, 2313.0169677886915, 2316.0388438797877, 2316.3166865774638, 2016.727741754859, 2096.90563924623, 2204.0853045985896, 2315.80315673168, 2317.2816426907543, 2244.607193741089, 2298.310506847939, 2308.0652335804025, 2316.212487754172, 2320.640300753369, 8926.285897128215, 9004.459732416077, 9011.909880901238, 9017.774555636095, 9067.365284430092, 5143.8456846294675, 5309.0, 5310.0, 5315.255864179018, 5321.840344789663, 3037.665856755755, 3355.4295769719206, 3485.250671105699, 3557.1640664443835, 3568.1971556399603, 939.7697564096771, 1048.7640280936805, 1086.559943222996, 1101.8071837428417, 1104.7403860092872, 11881.287122772575, 11924.986376158036, 11926.940365298118, 11927.821082683766, 11932.164748467698, 4376.409184760017, 4496.977515112403, 4666.65000124992, 4678.649101317346, 4700.435947686299, 10057.547410366551, 10068.345557538736, 10072.992700730016, 10076.09315161639, 10083.899161008443, 5305.708186049727, 5306.09796334624, 5314.202869569518, 5318.282031925718, 5322.574194064466, 3765.224849895846, 4674.415698037746, 4880.926786098212, 4881.999190166008, 4882.048000639841, 3985.1830374773103, 3988.541317748438, 4054.412110243998, 4075.286824805647, 4129.2773764591075, 1274.5411651805343, 1275.853276873153, 1279.4050766393486, 1287.2662582327982, 1288.890444312225, 894.1148263219496, 920.2408013388981, 928.2944961829058, 946.3470205558143, 952.8094381123803, 4941.703497790072, 5740.655560666373, 5846.912296315559, 5988.640681559121, 6111.236095488064, 380.2281368821297, 391.77668728824864, 394.5029263128479, 401.582354351477, 410.1591736939263, 3900.902477438087, 3932.8791294480934, 3935.2326296371934, 4049.493813273341, 4108.50697916246, 10684.893151068545, 10987.61543345979, 11116.61091861781, 11130.610428634958, 11165.385903775208, 11066.944665276622, 11079.446027698583, 11087.556497740161, 11100.555977760961, 11109.444527773741, 8168.550729709804, 8255.42212045151, 8264.793380165544, 8268.875966860502, 8281.710140145187, 5696.1455781633085, 5772.19189313488, 5941.494972927349, 5952.404759524186, 5961.821145365646, 8689.044205137556, 10298.948505257726, 10327.225458090823, 10398.844017339594, 10430.58277668915, 10547.736306592089, 10554.261201715806, 10562.049415552638, 10570.947145264225, 10595.311304765166, 6956.756513522002, 7208.243134471015, 7433.553986760811, 7664.770056898302, 7724.729634462767, 8408.747737567883, 8763.737087887373, 8767.649294028168, 8778.956105219433, 8781.956090219508, 6712.563683360566, 7597.582132982739, 7754.651040703295, 7766.728164514215, 7875.8818617719635, 7509.699612015458, 7575.545467271982, 7659.693612255447, 7697.114831794192, 7767.961160194162, 7129.607871567114, 7283.781486555412, 7288.416926645961, 7298.56208627484, 7299.6350182489605, 4862.513748625109, 4863.221884498464, 4995.825146119869, 5063.5695965843315, 5131.974340128276, 4930.753462326941, 5006.4242612098615, 5032.647714659939, 5051.570616497639, 5243.764030618555], "value": 4488.8336736740775, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.13589135073687, 306.598356153439, 315.15224047312734, 316.62954343418215, 318.67654330854174, 5442.619016668835, 5461.617686761923, 5481.57401623985, 5498.917516237259, 5508.862278443046, 5042.495750424956, 5073.23901414788, 5241.737913104349, 5398.16328469087, 5405.324379421992, 3755.896697849213, 4082.326416141333, 4123.3196522573735, 4142.730722503043, 4169.0, 3585.354636165489, 4131.0, 4154.480689913761, 4155.522114956773, 4167.166566686659, 3465.1239471238937, 3864.787436690981, 4068.938965915499, 4096.549379568245, 4132.917341653165, 3619.3304238715923, 4092.6725861930972, 4093.3246014407737, 4099.631033207026, 4099.97950010251, 3160.6567208936112, 3336.899893003214, 3545.663161999606, 3554.48459973305, 3554.6267641897743, 4270.722403043792, 4928.6303527235605, 4944.6785958912915, 5218.530332270114, 5262.447443018466, 4304.39738436616, 4666.32338310943, 4700.083483720676, 4710.835120770791, 4711.740854253015, 5650.446127315021, 5697.259356283698, 5702.344230413514, 5708.942910570924, 5717.170505438261, 3889.552701439314, 3895.941560876589, 3902.453656488094, 3912.317703387542, 3916.3733802591732, 4502.941808674958, 5461.15352120422, 5689.687067211302, 5916.615419997686, 5979.312379076375, 3172.428962786674, 3813.1420430402936, 3921.3333733264844, 3942.3692209246383, 3969.146633473817, 3963.900902477461, 3978.4430179774854, 3981.0, 3993.983038565506, 3995.4606128172904, 3960.1287716702855, 4358.063016451478, 4385.815829725956, 4465.843695470708, 4655.813767449264, 4770.76146192689, 5718.513926316226, 5747.3103227612955, 5775.0, 5784.045632470679, 1287.4528325461642, 1336.7460182565283, 1365.1877133105786, 1365.6312795545346, 1367.2070199284392, 783.1659282863732, 841.8442588121217, 867.4881819726265, 870.7257213977576, 876.5529579914162, 4520.638348932078, 4715.89176543512, 5220.582353411718, 5394.541463975527, 5402.567794576425, 4990.6257030722845, 5592.573893657172, 5797.217375654317, 5918.526517878646, 5933.0, 4186.853460128881, 4455.910881782346, 5239.895202095937, 5241.895162096736, 5285.524302812765, 2579.4841031793717, 2629.907953221628, 2896.797224194286, 3051.5117581187346, 3071.969280307213, 4627.398438203012, 4870.537298956593, 5772.278465191851, 5991.640501569833, 6062.272527296753, 5517.613767036269, 5945.227120474397, 5991.64050156992, 6032.909506357408, 6071.694585664103, 5310.814121505806, 5792.410324037324, 5955.851103722442, 6002.319350581844, 6057.848553786191, 5188.9740551297, 5637.351704554028, 5814.56940405163, 5841.970790146022, 5885.61743486672, 5328.733563321741, 5557.304707216916, 5803.39064398249, 5817.7963771267805, 5835.56233282497, 4454.26504626733, 5656.0, 6065.60573562717, 6077.696115194134, 6082.270127584541, 3431.279431319451, 3578.481120237499, 3627.8572249741246, 3847.4414514347645, 3881.4954055972544, 5227.849873027928, 5370.824815949547, 5849.660135945655, 5912.42809377922, 5926.436988486087, 4809.711417314972, 5153.974230128825, 5270.8945821084135, 5407.270018547446, 5767.798127065533, 4874.8537543873745, 5787.450192231731, 5792.727109183298, 5792.94207057924, 5823.961613435264, 4919.926201107058, 5397.325334333208, 5619.353774316006, 5633.845061762411, 5724.2809665408595, 4752.954350042985, 5652.523697925828, 5726.997775389207, 5768.634859707499, 5791.942080579141, 5558.443116166447, 5569.443055694398, 5577.247071645276, 5580.86047848807, 5584.609077364546], "value": 4113.263147459066, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.4493542827169, 746.8058304840741, 770.9267619576138, 854.3891117850735, 856.9528675922821, 15353.078815271077, 15915.681686366266, 15937.681246375065, 15943.441979530719, 16053.0, 14512.782308265385, 14667.633309167277, 14699.559013229593, 14717.779233311509, 14723.705525889476, 713.4399496395331, 737.1301863800713, 770.7572114783853, 797.9626485568766, 884.9601767920443, 887.1616322575169, 895.194325107402, 895.2032690905099, 896.1531352871539, 896.4621227263655, 973.1484950668165, 974.2108891797667, 974.7855471796194, 977.3891317926297, 977.9902200977971, 962.0475729028282, 962.6630679262237, 963.1476143612883, 964.9530259668269, 965.4159233663603, 555.8304717061295, 686.9759558415432, 713.6717110129347, 714.8748968930469, 717.9856402871914, 14662.41350345985, 14793.852061479463, 14799.334029968677, 14800.555983320517, 14803.703925921422, 13739.24434156131, 14005.5098071567, 14191.858081419157, 14192.290385480788, 14370.712585748226, 12843.0, 12909.0, 12934.741305173844, 12989.740205195938, 13284.933575332157, 4638.443386793572, 4814.15752243356, 4819.6867203632, 4821.276808478721, 4865.951340486586, 105411.0, 106620.0, 106721.46639266831, 110944.44527777309, 111412.4429377856, 11014.339139651647, 11216.270942388719, 11219.439028048564, 11449.0, 11741.765164696659, 21848.0, 22332.665010024863, 22369.88815055914, 22376.0, 22377.664335034988, 7894.289513943772, 7933.722319718782, 7965.641546130438, 8004.51972881629, 8161.5919204039565, 4385.7149285296355, 4403.933940990888, 4406.162829062468, 4470.307102399133, 4596.8161273548685, 3327.9500807487907, 3391.423458012144, 3399.184195792992, 3431.9828400858337, 3434.690877821008, 531.9440909794056, 611.8776244751068, 613.2702084519408, 615.722924683894, 625.337142628815, 517.0202466326291, 519.1874716069415, 582.8105865593699, 599.0983569727532, 651.6513665189195, 7506.549607023596, 8357.58212089393, 8598.011228708654, 8701.825963480695, 8763.693270735494, 534.5670007294026, 604.8900268008166, 606.236142460503, 610.5939550199157, 646.5732616473106, 4160.708750387505, 4282.379055037064, 4292.48490181174, 4358.411614432074, 4363.331654105745, 104001.0, 140908.2954585241, 143244.0, 144329.27835360757, 144643.2767836175, 102523.0, 118466.0, 121153.0, 121180.0, 121881.29652869178, 79246.0, 108478.0, 109597.45201274102, 109888.0, 110897.89102108876, 8249.752507424786, 8282.006159260927, 8285.54429506383, 8390.664373424994, 8477.522398140616, 61969.69015154895, 63570.68214658897, 64068.3593164081, 64182.679086604265, 65091.698166035465, 86443.13556864603, 89048.0, 91135.54432227796, 91868.54065729628, 94882.10235795108, 11501.2524185931, 11503.367314797766, 11633.709157270798, 11716.0, 12252.142350035412, 12249.87750122523, 12280.508779649072, 12363.69090772738, 12451.066170037102, 12994.35028248565, 11695.76608467843, 12261.509539618672, 12266.32535210572, 12312.076594255288, 12388.318642474751, 10865.891341086488, 13203.801942971055, 13745.862541374458, 13747.725045499234, 14074.0, 13415.932920335727, 13787.103838250481, 13939.721205576034, 14217.786733199217, 14413.567592972227, 10700.518476668412, 10962.0, 10964.451777410937, 11105.333679979227, 11769.234999724991, 11262.436878155895, 11340.20618556693, 11373.0, 11373.260738052331, 11592.246503977214], "value": 7706.544555345516, "unit": "score"}], "name": "dromaeo_css", "value": 7562.387368087074, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 113.0, 114.0, 114.0, 113.0, 113.0, 112.0, 111.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 109.0, 109.0, 169.0, 135.0, 135.0, 128.0, 105.0, 163.0, 106.0], "value": 130.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 177.0, 184.0, 182.0, 184.0, 180.0, 181.0, 183.0, 181.0, 183.0], "value": 182.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 58.0, 88.0, 58.0, 58.0, 65.0, 61.0, 83.0, 60.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 95.0, 72.0, 77.0, 68.0, 95.0, 69.0, 69.0, 102.0], "value": 81.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 87.0, 84.0, 88.0, 85.0, 88.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 107.0, 148.0], "value": 100.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 84.0, 85.0, 94.0, 122.0, 86.0, 84.0, 86.0, 85.0], "value": 89.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 53.0, 51.0, 51.0, 54.0, 51.0, 53.0, 52.0, 51.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 75.0, 84.0, 78.0, 72.0, 72.0, 72.0, 72.0, 72.0, 73.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 86.0, 84.0, 84.0, 86.0, 83.0, 99.0, 104.0, 98.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 138.0, 144.0, 158.0, 150.0, 135.0, 136.0, 226.0, 133.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [223.0, 173.0, 167.0, 177.0, 167.0, 166.0, 168.0, 169.0, 165.0, 169.0], "value": 174.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 66.0, 67.0, 68.0, 71.0, 82.0, 85.0], "value": 70.8, "unit": "score"}], "name": "kraken", "value": 1461.4, "alertThreshold": 2.0}]} 22:40:51 INFO - Return code: 0 22:40:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:40:51 INFO - # TBPL SUCCESS # 22:40:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:40:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:40:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:40:51 INFO - 2016-07-20 22:40:51.869 screenresolution[1666:9681] starting screenresolution argv=screenresolution get 22:40:51 INFO - 2016-07-20 22:40:51.872 screenresolution[1666:9681] Display 0: 1600x1200x32@60 22:40:51 INFO - 2016-07-20 22:40:51.878 screenresolution[1667:9684] starting screenresolution argv=screenresolution list 22:40:51 INFO - Available Modes on Display 0 22:40:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:40:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:40:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:40:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:40:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:40:52 INFO - Intel Iris: 22:40:52 INFO - Chipset Model: Intel Iris 22:40:52 INFO - Type: GPU 22:40:52 INFO - Bus: Built-In 22:40:52 INFO - VRAM (Dynamic, Max): 1536 MB 22:40:52 INFO - Vendor: Intel (0x8086) 22:40:52 INFO - Device ID: 0x0a2e 22:40:52 INFO - Revision ID: 0x0009 22:40:52 INFO - Displays: 22:40:52 INFO - Display: 22:40:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:40:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:40:52 INFO - Display Serial Number: 1600x1200 60 22:40:52 INFO - Main Display: Yes 22:40:52 INFO - Mirror: Off 22:40:52 INFO - Online: Yes 22:40:52 INFO - Rotation: Supported 22:40:52 INFO - Return code: 0 22:40:52 INFO - Running post-action listener: _resource_record_post_action 22:40:52 INFO - [mozharness: 2016-07-21 05:40:52.121103Z] Finished run-tests step (success) 22:40:52 INFO - Running post-run listener: _resource_record_post_run 22:40:52 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 37180416; Write bytes: 706428928; Read time: 250; Write time: 1977 22:40:52 INFO - TinderboxPrint: CPU usage
53.5% 22:40:52 INFO - TinderboxPrint: I/O read bytes / time
37,180,416 / 250 22:40:52 INFO - TinderboxPrint: I/O write bytes / time
706,428,928 / 1,977 22:40:52 INFO - TinderboxPrint: CPU idle
2,255.6 (46.5%) 22:40:52 INFO - TinderboxPrint: CPU system
970.7 (20.0%) 22:40:52 INFO - TinderboxPrint: CPU user
1,623.3 (33.5%) 22:40:52 INFO - TinderboxPrint: Swap in / out
445,059,072 / 0 22:40:52 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393351680; Write bytes: 387356160; Read time: 29524; Write time: 808 22:40:52 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 36217856; Write bytes: 296032768; Read time: 237; Write time: 1116 22:40:52 INFO - Running post-run listener: _upload_blobber_files 22:40:52 INFO - Blob upload gear active. 22:40:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:40:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:40:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:40:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:40:53 INFO - (blobuploader) - INFO - Open directory for files ... 22:40:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:40:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:40:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:40:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:40:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:40:53 INFO - (blobuploader) - INFO - Done attempting. 22:40:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:40:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:40:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:40:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:40:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:40:54 INFO - (blobuploader) - INFO - Done attempting. 22:40:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:40:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:40:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:40:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:40:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:40:57 INFO - (blobuploader) - INFO - Done attempting. 22:40:57 INFO - (blobuploader) - INFO - Iteration through files over. 22:40:57 INFO - Return code: 0 22:40:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:40:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:40:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e818666365b3b6dccaef9d142a97e5ed8df248178497a771ae035191ed1aecee1c9845618497a270a1a5d61678fd6832e31e2489bce389db8574b4c3ad1f2192", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e1f1a90aeca6edb84a3f1466e00d87562da4df75555202d9a32fa28c34ced74ec321d076ce0ae8ab3565c1d2c13b50a3a9bc0bbff588bc41bf305cc65b7aaa7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0a27520381d56719e8c8d362d73b6fa930500990f7ec5bf2632f8dba56884c1851caf2a7935a4e5525c3e66ceab4421db37055353bb1467edef1f218f80edaf7"} 22:40:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:40:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:40:57 INFO - Contents: 22:40:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e818666365b3b6dccaef9d142a97e5ed8df248178497a771ae035191ed1aecee1c9845618497a270a1a5d61678fd6832e31e2489bce389db8574b4c3ad1f2192", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e1f1a90aeca6edb84a3f1466e00d87562da4df75555202d9a32fa28c34ced74ec321d076ce0ae8ab3565c1d2c13b50a3a9bc0bbff588bc41bf305cc65b7aaa7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0a27520381d56719e8c8d362d73b6fa930500990f7ec5bf2632f8dba56884c1851caf2a7935a4e5525c3e66ceab4421db37055353bb1467edef1f218f80edaf7"} 22:40:57 INFO - Running post-run listener: copy_logs_to_upload_dir 22:40:57 INFO - Copying logs to upload dir... 22:40:57 INFO - mkdir: /builds/slave/test/build/upload/logs 22:40:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.818050 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-20 22:40:57.867732) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 22:40:57.871310) ========= 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.ehGyWyl1K8/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.JnPkPdByg2/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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/e818666365b3b6dccaef9d142a97e5ed8df248178497a771ae035191ed1aecee1c9845618497a270a1a5d61678fd6832e31e2489bce389db8574b4c3ad1f2192", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e1f1a90aeca6edb84a3f1466e00d87562da4df75555202d9a32fa28c34ced74ec321d076ce0ae8ab3565c1d2c13b50a3a9bc0bbff588bc41bf305cc65b7aaa7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0a27520381d56719e8c8d362d73b6fa930500990f7ec5bf2632f8dba56884c1851caf2a7935a4e5525c3e66ceab4421db37055353bb1467edef1f218f80edaf7"} build_url:https://queue.taskcluster.net/v1/task/BZSFA8ZVQvi5llg8cY-0ug/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010655 build_url: 'https://queue.taskcluster.net/v1/task/BZSFA8ZVQvi5llg8cY-0ug/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/e818666365b3b6dccaef9d142a97e5ed8df248178497a771ae035191ed1aecee1c9845618497a270a1a5d61678fd6832e31e2489bce389db8574b4c3ad1f2192", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e1f1a90aeca6edb84a3f1466e00d87562da4df75555202d9a32fa28c34ced74ec321d076ce0ae8ab3565c1d2c13b50a3a9bc0bbff588bc41bf305cc65b7aaa7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0a27520381d56719e8c8d362d73b6fa930500990f7ec5bf2632f8dba56884c1851caf2a7935a4e5525c3e66ceab4421db37055353bb1467edef1f218f80edaf7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 22:40:57.903080) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 22:40:57.903404) ========= 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.ehGyWyl1K8/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.JnPkPdByg2/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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.005084 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 22:40:57.952943) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 22:40:57.953408) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 22:40:57.953937) ========= ========= Total master_lag: 2.90 =========