builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0137 starttime: 1473497155.37 results: success (0) buildid: 20160910001320 builduid: 7e867babc88a444088bcd9eae7563b7e revision: 61cc64967515bfa50f957d2f5946e1f20bc3a6c3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 01:45:55.372771) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 01:45:55.373222) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 01:45:55.373532) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 01:45:55.642557) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 01:45:55.642887) ========= 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.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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-09-10 01:45:55-- 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% 12.7M=0.001s 2016-09-10 01:45:56 (12.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.458327 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 01:45:56.130228) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 01:45:56.130622) ========= 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.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.028924 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 01:45:56.180025) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-10 01:45:56.180407) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 61cc64967515bfa50f957d2f5946e1f20bc3a6c3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 61cc64967515bfa50f957d2f5946e1f20bc3a6c3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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-09-10 01:45:56,261 truncating revision to first 12 chars 2016-09-10 01:45:56,261 Setting DEBUG logging. 2016-09-10 01:45:56,261 attempt 1/10 2016-09-10 01:45:56,261 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/61cc64967515?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-10 01:45:57,050 unpacking tar archive at: mozilla-central-61cc64967515/testing/mozharness/ program finished with exit code 0 elapsedTime=1.052413 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-10 01:45:57.255689) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 01:45:57.256061) ========= script_repo_revision: 61cc64967515bfa50f957d2f5946e1f20bc3a6c3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 01:45:57.256483) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 01:45:57.256808) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 01:45:57.278266) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-10 01:45:57.278590) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 01:45:57 INFO - MultiFileLogger online at 20160910 01:45:57 in /builds/slave/test 01:45:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 01:45:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:45:57 INFO - {'append_to_log': False, 01:45:57 INFO - 'base_work_dir': '/builds/slave/test', 01:45:57 INFO - 'blob_upload_branch': 'Firefox', 01:45:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:45:57 INFO - 'branch': 'Firefox', 01:45:57 INFO - 'buildbot_json_path': 'buildprops.json', 01:45:57 INFO - 'config_files': ('talos/mac_config.py',), 01:45:57 INFO - 'default_actions': ('clobber', 01:45:57 INFO - 'read-buildbot-config', 01:45:57 INFO - 'download-and-extract', 01:45:57 INFO - 'populate-webroot', 01:45:57 INFO - 'create-virtualenv', 01:45:57 INFO - 'install', 01:45:57 INFO - 'run-tests'), 01:45:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:45:57 INFO - 'download_minidump_stackwalk': True, 01:45:57 INFO - 'download_symbols': 'ondemand', 01:45:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:45:57 INFO - 'tooltool.py': '/tools/tooltool.py', 01:45:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:45:57 INFO - '/tools/misc-python/virtualenv.py')}, 01:45:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:45:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:45:57 INFO - 'installer_path': 'installer.exe', 01:45:57 INFO - 'log_level': 'info', 01:45:57 INFO - 'log_name': 'talos', 01:45:57 INFO - 'log_to_console': True, 01:45:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:45:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:45:57 INFO - 'opt_config_files': (), 01:45:57 INFO - 'pip_index': False, 01:45:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:45:57 INFO - 'cmd': ('bash', 01:45:57 INFO - '-c', 01:45:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:45:57 INFO - 'enabled': True, 01:45:57 INFO - 'halt_on_failure': False, 01:45:57 INFO - 'name': 'check_screen_resolution'},), 01:45:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:45:57 INFO - 'cmd': ('bash', 01:45:57 INFO - '-c', 01:45:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:45:57 INFO - 'enabled': True, 01:45:57 INFO - 'halt_on_failure': False, 01:45:57 INFO - 'name': 'check_screen_resolution'},), 01:45:57 INFO - 'run_cmd_checks_enabled': True, 01:45:57 INFO - 'sps_profile': False, 01:45:57 INFO - 'sps_profile_interval': 0, 01:45:57 INFO - 'suite': 'dromaeojs', 01:45:57 INFO - 'system_bits': '32', 01:45:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:45:57 INFO - 'title': 't-yosemite-r7-0137', 01:45:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:45:57 INFO - 'use_talos_json': True, 01:45:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:45:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:45:57 INFO - 'work_dir': 'build'} 01:45:57 INFO - [mozharness: 2016-09-10 08:45:57.485342Z] Running clobber step. 01:45:57 INFO - Running pre-action listener: _resource_record_pre_action 01:45:57 INFO - Running main action method: clobber 01:45:57 INFO - rmtree: /builds/slave/test/build 01:45:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:45:59 INFO - Running post-action listener: _resource_record_post_action 01:45:59 INFO - [mozharness: 2016-09-10 08:45:59.613027Z] Finished clobber step (success) 01:45:59 INFO - [mozharness: 2016-09-10 08:45:59.613157Z] Running read-buildbot-config step. 01:45:59 INFO - Running pre-action listener: _resource_record_pre_action 01:45:59 INFO - Running main action method: read_buildbot_config 01:45:59 INFO - Using buildbot properties: 01:45:59 INFO - { 01:45:59 INFO - "project": "", 01:45:59 INFO - "product": "firefox", 01:45:59 INFO - "script_repo_revision": "production", 01:45:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 01:45:59 INFO - "repository": "", 01:45:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 01:45:59 INFO - "buildid": "20160910001320", 01:45:59 INFO - "pgo_build": "False", 01:45:59 INFO - "basedir": "/builds/slave/test", 01:45:59 INFO - "buildnumber": 39, 01:45:59 INFO - "slavename": "t-yosemite-r7-0137", 01:45:59 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 01:45:59 INFO - "platform": "yosemite_r7", 01:45:59 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 01:45:59 INFO - "branch": "mozilla-central", 01:45:59 INFO - "repo_path": "mozilla-central", 01:45:59 INFO - "revision": "61cc64967515bfa50f957d2f5946e1f20bc3a6c3", 01:45:59 INFO - "stage_platform": "macosx64", 01:45:59 INFO - "builduid": "7e867babc88a444088bcd9eae7563b7e", 01:45:59 INFO - "slavebuilddir": "test" 01:45:59 INFO - } 01:45:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 01:45:59 INFO - Running post-action listener: _resource_record_post_action 01:45:59 INFO - [mozharness: 2016-09-10 08:45:59.616428Z] Finished read-buildbot-config step (success) 01:45:59 INFO - [mozharness: 2016-09-10 08:45:59.616525Z] Running download-and-extract step. 01:45:59 INFO - Running pre-action listener: _resource_record_pre_action 01:45:59 INFO - Running main action method: download_and_extract 01:45:59 INFO - mkdir: /builds/slave/test/build/tests 01:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:45:59 INFO - trying https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 01:45:59 INFO - Downloading https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 01:45:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 01:46:00 INFO - Downloaded 1199 bytes. 01:46:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 01:46:00 INFO - Using the following test package requirements: 01:46:00 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 01:46:00 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:46:00 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 01:46:00 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:46:00 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 01:46:00 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:46:00 INFO - u'jsshell-mac.zip'], 01:46:00 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:46:00 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 01:46:00 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 01:46:00 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:46:00 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 01:46:00 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:46:00 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 01:46:00 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:46:00 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 01:46:00 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:46:00 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 01:46:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:46:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 01:46:01 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 01:46:08 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 01:46:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 01:46:08 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 01:46:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 01:46:15 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 01:46:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:16 INFO - trying https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:46:16 INFO - Downloading https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 01:46:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 01:46:19 INFO - Downloaded 111604463 bytes. 01:46:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:46:19 INFO - mkdir: /builds/slave/test/properties 01:46:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:46:19 INFO - Writing to file /builds/slave/test/properties/build_url 01:46:19 INFO - Contents: 01:46:19 INFO - build_url:https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:46:20 INFO - Running post-action listener: _resource_record_post_action 01:46:20 INFO - Running post-action listener: set_extra_try_arguments 01:46:20 INFO - [mozharness: 2016-09-10 08:46:20.102623Z] Finished download-and-extract step (success) 01:46:20 INFO - [mozharness: 2016-09-10 08:46:20.102747Z] Running populate-webroot step. 01:46:20 INFO - Running pre-action listener: _resource_record_pre_action 01:46:20 INFO - Running main action method: populate_webroot 01:46:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:46:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:46:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:46:20 INFO - '--tppagecycles', 01:46:20 INFO - '7'], 01:46:20 INFO - 'tests': ['tsvgm']}}, 01:46:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:46:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:46:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:46:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:46:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:46:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:46:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:46:20 INFO - 'talos_options': ['--disable-e10s'], 01:46:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:46:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:46:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:46:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:46:20 INFO - 'talos_options': ['--disable-e10s'], 01:46:20 INFO - 'tests': ['damp', 'tps']}, 01:46:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:46:20 INFO - 'tests': ['damp', 'tps']}, 01:46:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:46:20 INFO - 'tests': ['dromaeo_dom']}, 01:46:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:46:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:46:20 INFO - 'tests': ['basic_compositor_video']}, 01:46:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:46:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:46:20 INFO - 'tests': ['a11yr', 01:46:20 INFO - 'ts_paint', 01:46:20 INFO - 'tpaint', 01:46:20 INFO - 'sessionrestore', 01:46:20 INFO - 'sessionrestore_no_auto_restore', 01:46:20 INFO - 'tabpaint']}, 01:46:20 INFO - 'other-e10s': {'tests': ['a11yr', 01:46:20 INFO - 'ts_paint', 01:46:20 INFO - 'tpaint', 01:46:20 INFO - 'sessionrestore', 01:46:20 INFO - 'sessionrestore_no_auto_restore', 01:46:20 INFO - 'tabpaint']}, 01:46:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:46:20 INFO - 'ts_paint', 01:46:20 INFO - 'tpaint', 01:46:20 INFO - 'sessionrestore', 01:46:20 INFO - 'sessionrestore_no_auto_restore', 01:46:20 INFO - 'tabpaint']}, 01:46:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:46:20 INFO - 'ts_paint', 01:46:20 INFO - 'tpaint', 01:46:20 INFO - 'sessionrestore', 01:46:20 INFO - 'sessionrestore_no_auto_restore', 01:46:20 INFO - 'tabpaint']}, 01:46:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:46:20 INFO - 'tests': ['a11yr', 01:46:20 INFO - 'ts_paint', 01:46:20 INFO - 'tpaint', 01:46:20 INFO - 'sessionrestore', 01:46:20 INFO - 'sessionrestore_no_auto_restore', 01:46:20 INFO - 'tabpaint']}, 01:46:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:46:20 INFO - 'tests': ['a11yr', 01:46:20 INFO - 'ts_paint', 01:46:20 INFO - 'tpaint', 01:46:20 INFO - 'sessionrestore', 01:46:20 INFO - 'sessionrestore_no_auto_restore', 01:46:20 INFO - 'tabpaint']}, 01:46:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:46:20 INFO - 'tests': ['tsvgx', 01:46:20 INFO - 'tsvgr_opacity', 01:46:20 INFO - 'tart', 01:46:20 INFO - 'tscrollx', 01:46:20 INFO - 'cart']}, 01:46:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:46:20 INFO - 'tsvgr_opacity', 01:46:20 INFO - 'tart', 01:46:20 INFO - 'tscrollx', 01:46:20 INFO - 'cart']}, 01:46:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:46:20 INFO - 'talos_options': ['--disable-e10s'], 01:46:20 INFO - 'tests': ['tp5o']}, 01:46:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:46:20 INFO - 'tests': ['tp5o']}, 01:46:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:46:20 INFO - 'talos_options': ['--disable-e10s', 01:46:20 INFO - '--xperf_path', 01:46:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:46:20 INFO - 'tests': ['tp5n']}, 01:46:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:46:20 INFO - 'talos_options': ['--xperf_path', 01:46:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:46:20 INFO - 'tests': ['tp5n']}}, 01:46:20 INFO - 'talos.zip': {'path': '', 01:46:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:46:20 INFO - Running post-action listener: _resource_record_post_action 01:46:20 INFO - [mozharness: 2016-09-10 08:46:20.121360Z] Finished populate-webroot step (success) 01:46:20 INFO - [mozharness: 2016-09-10 08:46:20.121478Z] Running create-virtualenv step. 01:46:20 INFO - Running pre-action listener: _resource_record_pre_action 01:46:20 INFO - Running main action method: create_virtualenv 01:46:20 INFO - Creating virtualenv /builds/slave/test/build/venv 01:46:20 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 01:46:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:46:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:46:20 INFO - Using real prefix '/tools/python27' 01:46:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:46:20 INFO - Installing distribute.............................................................................................................................................................................................done. 01:46:23 INFO - Installing pip.................done. 01:46:23 INFO - Return code: 0 01:46:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:46:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:46:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:23 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 0x1094e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10945e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec62d85410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092fcd30>, '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 0x109122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092f4b20>, '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.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 01:46:23 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 01:46:23 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 01:46:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eAVzvSxZGv/Render', 01:46:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:46:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:46:23 INFO - 'HOME': '/Users/cltbld', 01:46:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:46:23 INFO - 'LOGNAME': 'cltbld', 01:46:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:46:23 INFO - 'MOZ_NO_REMOTE': '1', 01:46:23 INFO - 'NO_EM_RESTART': '1', 01:46:23 INFO - 'PAGER': '/bin/cat', 01:46:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:46:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:46:23 INFO - 'PWD': '/builds/slave/test', 01:46:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:46:23 INFO - 'SHELL': '/bin/bash', 01:46:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OhKj6ZTlSX/Listeners', 01:46:23 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 01:46:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:46:23 INFO - 'USER': 'cltbld', 01:46:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:46:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:46:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:46:23 INFO - 'XPC_FLAGS': '0x0', 01:46:23 INFO - 'XPC_SERVICE_NAME': '0', 01:46:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:46:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:46:24 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 01:46:24 INFO - Cleaning up... 01:46:24 INFO - Return code: 0 01:46:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:46:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:46:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46: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', '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 0x1094e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10945e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec62d85410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092fcd30>, '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 0x109122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092f4b20>, '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.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 01:46: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', 'psutil>=3.1.1'] in /builds/slave/test/build 01:46: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 psutil>=3.1.1 01:46:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eAVzvSxZGv/Render', 01:46:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:46:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:46:24 INFO - 'HOME': '/Users/cltbld', 01:46:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:46:24 INFO - 'LOGNAME': 'cltbld', 01:46:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:46:24 INFO - 'MOZ_NO_REMOTE': '1', 01:46:24 INFO - 'NO_EM_RESTART': '1', 01:46:24 INFO - 'PAGER': '/bin/cat', 01:46:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:46:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:46:24 INFO - 'PWD': '/builds/slave/test', 01:46:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:46:24 INFO - 'SHELL': '/bin/bash', 01:46:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OhKj6ZTlSX/Listeners', 01:46:24 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 01:46:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:46:24 INFO - 'USER': 'cltbld', 01:46:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:46:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:46:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:46:24 INFO - 'XPC_FLAGS': '0x0', 01:46:24 INFO - 'XPC_SERVICE_NAME': '0', 01:46:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:46:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:46:24 INFO - Downloading/unpacking psutil>=3.1.1 01:46: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 01:46: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 01:46: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 01:46: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 01:46: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 01:46: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 01:46:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:46:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:46:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:46:27 INFO - Installing collected packages: psutil 01:46:27 INFO - Running setup.py install for psutil 01:46:27 INFO - building 'psutil._psutil_osx' extension 01:46:27 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 01:46:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 01:46:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 01:46:28 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 01:46:28 INFO - building 'psutil._psutil_posix' extension 01:46:28 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 01:46:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:46:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:46:28 INFO - ^ 01:46:28 INFO - 1 warning generated. 01:46:28 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 01:46:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:46:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:46:28 INFO - Successfully installed psutil 01:46:28 INFO - Cleaning up... 01:46:29 INFO - Return code: 0 01:46:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:46:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:46:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10945e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec62d85410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092fcd30>, '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 0x109122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092f4b20>, '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.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 01:46:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:46:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:46:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eAVzvSxZGv/Render', 01:46:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:46:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:46:29 INFO - 'HOME': '/Users/cltbld', 01:46:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:46:29 INFO - 'LOGNAME': 'cltbld', 01:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:46:29 INFO - 'MOZ_NO_REMOTE': '1', 01:46:29 INFO - 'NO_EM_RESTART': '1', 01:46:29 INFO - 'PAGER': '/bin/cat', 01:46:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:46:29 INFO - 'PWD': '/builds/slave/test', 01:46:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:46:29 INFO - 'SHELL': '/bin/bash', 01:46:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OhKj6ZTlSX/Listeners', 01:46:29 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 01:46:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:46:29 INFO - 'USER': 'cltbld', 01:46:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:46:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:46:29 INFO - 'XPC_FLAGS': '0x0', 01:46:29 INFO - 'XPC_SERVICE_NAME': '0', 01:46:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:46:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:46:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:46:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:46:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:46:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:46:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:46:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:46:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:46:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:46:31 INFO - Installing collected packages: mozsystemmonitor 01:46:31 INFO - Running setup.py install for mozsystemmonitor 01:46:31 INFO - Successfully installed mozsystemmonitor 01:46:31 INFO - Cleaning up... 01:46:32 INFO - Return code: 0 01:46:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:46:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:46:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:46:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10945e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec62d85410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092fcd30>, '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 0x109122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092f4b20>, '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.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 01:46:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:46:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:46:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eAVzvSxZGv/Render', 01:46:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:46:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:46:32 INFO - 'HOME': '/Users/cltbld', 01:46:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:46:32 INFO - 'LOGNAME': 'cltbld', 01:46:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:46:32 INFO - 'MOZ_NO_REMOTE': '1', 01:46:32 INFO - 'NO_EM_RESTART': '1', 01:46:32 INFO - 'PAGER': '/bin/cat', 01:46:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:46:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:46:32 INFO - 'PWD': '/builds/slave/test', 01:46:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:46:32 INFO - 'SHELL': '/bin/bash', 01:46:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OhKj6ZTlSX/Listeners', 01:46:32 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 01:46:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:46:32 INFO - 'USER': 'cltbld', 01:46:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:46:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:46:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:46:32 INFO - 'XPC_FLAGS': '0x0', 01:46:32 INFO - 'XPC_SERVICE_NAME': '0', 01:46:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:46:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:46:32 INFO - Downloading/unpacking jsonschema==2.5.1 01:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:46:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:46:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:46:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:46:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:46:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:46:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:46:34 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 01:46:34 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 01:46:34 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 01:46:34 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 01:46:34 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 01:46:34 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 01:46:35 INFO - Downloading functools32-3.2.3-2.tar.gz 01:46:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:46:35 INFO - warning: no files found matching '*.txt' 01:46:35 INFO - no previously-included directories found matching 'build' 01:46:35 INFO - no previously-included directories found matching 'dist' 01:46:35 INFO - no previously-included directories found matching '.git*' 01:46:35 INFO - Installing collected packages: jsonschema, functools32 01:46:35 INFO - Running setup.py install for functools32 01:46:35 INFO - warning: no files found matching '*.txt' 01:46:35 INFO - no previously-included directories found matching 'build' 01:46:35 INFO - no previously-included directories found matching 'dist' 01:46:35 INFO - no previously-included directories found matching '.git*' 01:46:35 INFO - Successfully installed jsonschema functools32 01:46:35 INFO - Cleaning up... 01:46:35 INFO - Return code: 0 01:46:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10945e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec62d85410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092fcd30>, '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 0x109122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092f4b20>, '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.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 01:46:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:46:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:46:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eAVzvSxZGv/Render', 01:46:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:46:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:46:35 INFO - 'HOME': '/Users/cltbld', 01:46:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:46:35 INFO - 'LOGNAME': 'cltbld', 01:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:46:35 INFO - 'MOZ_NO_REMOTE': '1', 01:46:35 INFO - 'NO_EM_RESTART': '1', 01:46:35 INFO - 'PAGER': '/bin/cat', 01:46:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:46:35 INFO - 'PWD': '/builds/slave/test', 01:46:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:46:35 INFO - 'SHELL': '/bin/bash', 01:46:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OhKj6ZTlSX/Listeners', 01:46:35 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 01:46:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:46:35 INFO - 'USER': 'cltbld', 01:46:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:46:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:46:35 INFO - 'XPC_FLAGS': '0x0', 01:46:35 INFO - 'XPC_SERVICE_NAME': '0', 01:46:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:46:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:46:35 INFO - Cleaning up... 01:46:35 INFO - Return code: 0 01:46:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10945e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec62d85410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092fcd30>, '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 0x109122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092f4b20>, '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.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 01:46:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:46:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:46:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eAVzvSxZGv/Render', 01:46:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:46:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:46:35 INFO - 'HOME': '/Users/cltbld', 01:46:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:46:35 INFO - 'LOGNAME': 'cltbld', 01:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:46:35 INFO - 'MOZ_NO_REMOTE': '1', 01:46:35 INFO - 'NO_EM_RESTART': '1', 01:46:35 INFO - 'PAGER': '/bin/cat', 01:46:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:46:35 INFO - 'PWD': '/builds/slave/test', 01:46:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:46:35 INFO - 'SHELL': '/bin/bash', 01:46:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OhKj6ZTlSX/Listeners', 01:46:35 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 01:46:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:46:35 INFO - 'USER': 'cltbld', 01:46:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:46:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:46:35 INFO - 'XPC_FLAGS': '0x0', 01:46:35 INFO - 'XPC_SERVICE_NAME': '0', 01:46:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:46:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:46:36 INFO - Downloading/unpacking blobuploader==1.2.4 01:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:46:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:46:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:46:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:46:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:46:38 INFO - Downloading blobuploader-1.2.4.tar.gz 01:46:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:46:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:46:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:46:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:46:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:46:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:46:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:46:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:46:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:46:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:46:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:46:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:46:39 INFO - Downloading docopt-0.6.1.tar.gz 01:46:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:46:39 INFO - Installing collected packages: blobuploader, requests, docopt 01:46:39 INFO - Running setup.py install for blobuploader 01:46:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:46:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:46:39 INFO - Running setup.py install for requests 01:46:40 INFO - Running setup.py install for docopt 01:46:40 INFO - Successfully installed blobuploader requests docopt 01:46:40 INFO - Cleaning up... 01:46:40 INFO - Return code: 0 01:46:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10945e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec62d85410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092fcd30>, '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 0x109122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092f4b20>, '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.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 01:46:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:46:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eAVzvSxZGv/Render', 01:46:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:46:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:46:40 INFO - 'HOME': '/Users/cltbld', 01:46:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:46:40 INFO - 'LOGNAME': 'cltbld', 01:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:46:40 INFO - 'MOZ_NO_REMOTE': '1', 01:46:40 INFO - 'NO_EM_RESTART': '1', 01:46:40 INFO - 'PAGER': '/bin/cat', 01:46:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:46:40 INFO - 'PWD': '/builds/slave/test', 01:46:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:46:40 INFO - 'SHELL': '/bin/bash', 01:46:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OhKj6ZTlSX/Listeners', 01:46:40 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 01:46:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:46:40 INFO - 'USER': 'cltbld', 01:46:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:46:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:46:40 INFO - 'XPC_FLAGS': '0x0', 01:46:40 INFO - 'XPC_SERVICE_NAME': '0', 01:46:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:46:40 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Jd5vds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:46:41 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-XDj303-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:46:41 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-3GvMPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:46:41 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-z36dl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:46:41 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-oz2g4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:46:41 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-4y7smO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:46:41 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-EkVvBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:46:41 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-rhlceB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:46:41 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-vV9UY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:46:41 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-1BZqml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:46:42 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-qzCOMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:46:42 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-m0jHVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:46:42 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-9BikVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:46:42 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-BCkgB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:46:42 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-B8NcIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:46:42 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-j931WA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:46:42 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-lvY8nG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:46:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:46:42 INFO - Running setup.py install for manifestparser 01:46:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:46:42 INFO - Running setup.py install for mozcrash 01:46:43 INFO - Running setup.py install for mozdebug 01:46:43 INFO - Running setup.py install for mozdevice 01:46:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:46:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:46:43 INFO - Running setup.py install for mozfile 01:46:43 INFO - Running setup.py install for mozhttpd 01:46:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:46:43 INFO - Running setup.py install for mozinfo 01:46:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:46:43 INFO - Running setup.py install for mozInstall 01:46:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:46:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:46:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:46:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:46:43 INFO - Running setup.py install for mozleak 01:46:44 INFO - Running setup.py install for mozlog 01:46:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:46:44 INFO - Running setup.py install for moznetwork 01:46:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:46:44 INFO - Running setup.py install for mozprocess 01:46:44 INFO - Running setup.py install for mozprofile 01:46:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:46:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:46:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:46:44 INFO - Running setup.py install for mozrunner 01:46:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:46:44 INFO - Running setup.py install for mozscreenshot 01:46:44 INFO - Running setup.py install for moztest 01:46:45 INFO - Running setup.py install for mozversion 01:46:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:46:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:46:45 INFO - Cleaning up... 01:46:45 INFO - Return code: 0 01:46:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10945e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec62d85410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092fcd30>, '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 0x109122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092f4b20>, '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.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 01:46:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:46:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:46:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eAVzvSxZGv/Render', 01:46:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:46:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:46:45 INFO - 'HOME': '/Users/cltbld', 01:46:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:46:45 INFO - 'LOGNAME': 'cltbld', 01:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:46:45 INFO - 'MOZ_NO_REMOTE': '1', 01:46:45 INFO - 'NO_EM_RESTART': '1', 01:46:45 INFO - 'PAGER': '/bin/cat', 01:46:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:46:45 INFO - 'PWD': '/builds/slave/test', 01:46:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:46:45 INFO - 'SHELL': '/bin/bash', 01:46:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OhKj6ZTlSX/Listeners', 01:46:45 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 01:46:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:46:45 INFO - 'USER': 'cltbld', 01:46:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:46:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:46:45 INFO - 'XPC_FLAGS': '0x0', 01:46:45 INFO - 'XPC_SERVICE_NAME': '0', 01:46:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:46:45 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-VJn10n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:46:45 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Icm_A1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:46:45 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-tgjCam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:46:45 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-FeGnSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:46:46 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)) 01:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:46:46 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-o_ri3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:46:46 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Q6RgUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:46:46 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-VTAdbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:46:46 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-8pwzYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:46:46 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-letZtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:46:46 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-HTfxQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:46:46 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-VdjTBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:46:46 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-bWGU78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:46:46 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-v5PVIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:46:46 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-mHLAVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:46:47 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-GyGmEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:46:47 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-VIpkaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:46:47 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-3Mjrkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:46:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:46:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:46:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:46:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:46:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:46:49 INFO - Downloading blessings-1.6.tar.gz 01:46:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:46:49 INFO - Installing collected packages: blessings 01:46:49 INFO - Running setup.py install for blessings 01:46:50 INFO - Successfully installed blessings 01:46:50 INFO - Cleaning up... 01:46:50 INFO - Return code: 0 01:46:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:46:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:46:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:46:50 INFO - Reading from file tmpfile_stdout 01:46:50 INFO - Current package versions: 01:46:50 INFO - blessings == 1.6 01:46:50 INFO - blobuploader == 1.2.4 01:46:50 INFO - docopt == 0.6.1 01:46:50 INFO - functools32 == 3.2.3-2 01:46:50 INFO - jsonschema == 2.5.1 01:46:50 INFO - manifestparser == 1.1 01:46:50 INFO - mozInstall == 1.12 01:46:50 INFO - mozcrash == 0.17 01:46:50 INFO - mozdebug == 0.1 01:46:50 INFO - mozdevice == 0.48 01:46:50 INFO - mozfile == 1.2 01:46:50 INFO - mozhttpd == 0.7 01:46:50 INFO - mozinfo == 0.9 01:46:50 INFO - mozleak == 0.1 01:46:50 INFO - mozlog == 3.3 01:46:50 INFO - moznetwork == 0.27 01:46:50 INFO - mozprocess == 0.23 01:46:50 INFO - mozprofile == 0.28 01:46:50 INFO - mozrunner == 6.12 01:46:50 INFO - mozscreenshot == 0.1 01:46:50 INFO - mozsystemmonitor == 0.3 01:46:50 INFO - moztest == 0.7 01:46:50 INFO - mozversion == 1.4 01:46:50 INFO - psutil == 3.1.1 01:46:50 INFO - requests == 1.2.3 01:46:50 INFO - wsgiref == 0.1.2 01:46:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10945e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec62d85410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092fcd30>, '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 0x109122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092f4b20>, '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.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 01:46:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:46:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:46:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eAVzvSxZGv/Render', 01:46:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:46:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:46:50 INFO - 'HOME': '/Users/cltbld', 01:46:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:46:50 INFO - 'LOGNAME': 'cltbld', 01:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:46:50 INFO - 'MOZ_NO_REMOTE': '1', 01:46:50 INFO - 'NO_EM_RESTART': '1', 01:46:50 INFO - 'PAGER': '/bin/cat', 01:46:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:46:50 INFO - 'PWD': '/builds/slave/test', 01:46:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:46:50 INFO - 'SHELL': '/bin/bash', 01:46:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OhKj6ZTlSX/Listeners', 01:46:50 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 01:46:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:46:50 INFO - 'USER': 'cltbld', 01:46:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:46:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:46:50 INFO - 'XPC_FLAGS': '0x0', 01:46:50 INFO - 'XPC_SERVICE_NAME': '0', 01:46:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:46:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:46:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:46:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:46:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:46:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:46:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:46:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:46:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:46:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:46:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:46:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:46:50 INFO - Cleaning up... 01:46:50 INFO - Return code: 0 01:46:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:46:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10945e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec62d85410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092fcd30>, '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 0x109122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092f4b20>, '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.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 01:46:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:46:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:46:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eAVzvSxZGv/Render', 01:46:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:46:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:46:50 INFO - 'HOME': '/Users/cltbld', 01:46:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:46:50 INFO - 'LOGNAME': 'cltbld', 01:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:46:50 INFO - 'MOZ_NO_REMOTE': '1', 01:46:50 INFO - 'NO_EM_RESTART': '1', 01:46:50 INFO - 'PAGER': '/bin/cat', 01:46:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:46:50 INFO - 'PWD': '/builds/slave/test', 01:46:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:46:50 INFO - 'SHELL': '/bin/bash', 01:46:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OhKj6ZTlSX/Listeners', 01:46:50 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 01:46:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:46:50 INFO - 'USER': 'cltbld', 01:46:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:46:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:46:50 INFO - 'XPC_FLAGS': '0x0', 01:46:50 INFO - 'XPC_SERVICE_NAME': '0', 01:46:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:46:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:46:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:46:51 INFO - Cleaning up... 01:46:51 INFO - Return code: 0 01:46:51 INFO - Running post-action listener: _resource_record_post_action 01:46:51 INFO - Running post-action listener: _start_resource_monitoring 01:46:51 INFO - Starting resource monitoring. 01:46:51 INFO - [mozharness: 2016-09-10 08:46:51.082488Z] Finished create-virtualenv step (success) 01:46:51 INFO - [mozharness: 2016-09-10 08:46:51.083482Z] Running install step. 01:46:51 INFO - Running pre-action listener: _resource_record_pre_action 01:46:51 INFO - Running main action method: install 01:46:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:46:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:46:51 INFO - Reading from file tmpfile_stdout 01:46:51 INFO - Detecting whether we're running mozinstall >=1.0... 01:46:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:46:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:46:51 INFO - Reading from file tmpfile_stdout 01:46:51 INFO - Output received: 01:46:51 INFO - Usage: mozinstall [options] installer 01:46:51 INFO - Options: 01:46:51 INFO - -h, --help show this help message and exit 01:46:51 INFO - -d DEST, --destination=DEST 01:46:51 INFO - Directory to install application into. [default: 01:46:51 INFO - "/builds/slave/test"] 01:46:51 INFO - --app=APP Application being installed. [default: firefox] 01:46:51 INFO - mkdir: /builds/slave/test/build/application 01:46:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:46:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 01:47:25 INFO - Reading from file tmpfile_stdout 01:47:25 INFO - Output received: 01:47:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:47:25 INFO - Running post-action listener: _resource_record_post_action 01:47:25 INFO - [mozharness: 2016-09-10 08:47:25.878881Z] Finished install step (success) 01:47:25 INFO - [mozharness: 2016-09-10 08:47:25.879023Z] Running run-tests step. 01:47:25 INFO - Running pre-action listener: _resource_record_pre_action 01:47:25 INFO - Running main action method: run_tests 01:47:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:47:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:47:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:47:25 INFO - 2016-09-10 01:47:25.893 screenresolution[1665:12415] starting screenresolution argv=screenresolution get 01:47:25 INFO - 2016-09-10 01:47:25.896 screenresolution[1665:12415] Display 0: 1600x1200x32@60 01:47:25 INFO - 2016-09-10 01:47:25.902 screenresolution[1666:12417] starting screenresolution argv=screenresolution list 01:47:25 INFO - Available Modes on Display 0 01:47:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:47:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:47:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:47:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:47:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:47:26 INFO - Intel Iris: 01:47:26 INFO - Chipset Model: Intel Iris 01:47:26 INFO - Type: GPU 01:47:26 INFO - Bus: Built-In 01:47:26 INFO - VRAM (Dynamic, Max): 1536 MB 01:47:26 INFO - Vendor: Intel (0x8086) 01:47:26 INFO - Device ID: 0x0a2e 01:47:26 INFO - Revision ID: 0x0009 01:47:26 INFO - Displays: 01:47:26 INFO - Display: 01:47:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:47:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:47:26 INFO - Display Serial Number: 1600x1200 60 01:47:26 INFO - Main Display: Yes 01:47:26 INFO - Mirror: Off 01:47:26 INFO - Online: Yes 01:47:26 INFO - Rotation: Supported 01:47:26 INFO - Return code: 0 01:47:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:47:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:47:26 INFO - Python 2.7.3 01:47:26 INFO - Return code: 0 01:47:26 INFO - grabbing minidump binary from tooltool 01:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:26 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 0x1092fcd30>, '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 0x109122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092f4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:47:26 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 01:47:26 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 01:47:26 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 01:47:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:47:26 INFO - Return code: 0 01:47:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:47:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:47:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:47:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:47:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:47:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--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 01:47:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0137 --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 01:47:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eAVzvSxZGv/Render', 01:47:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:47:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:47:26 INFO - 'HOME': '/Users/cltbld', 01:47:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:47:26 INFO - 'LOGNAME': 'cltbld', 01:47:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:47:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:47:26 INFO - 'MOZ_NO_REMOTE': '1', 01:47:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:47:26 INFO - 'NO_EM_RESTART': '1', 01:47:26 INFO - 'PAGER': '/bin/cat', 01:47:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:47:26 INFO - 'PWD': '/builds/slave/test', 01:47:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:47:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:47:26 INFO - 'SHELL': '/bin/bash', 01:47:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OhKj6ZTlSX/Listeners', 01:47:26 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 01:47:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:47:26 INFO - 'USER': 'cltbld', 01:47:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:47:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:47:26 INFO - 'XPC_FLAGS': '0x0', 01:47:26 INFO - 'XPC_SERVICE_NAME': '0', 01:47:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:47:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--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 01:47:26 INFO - mozversion application_buildid: 20160910001320 01:47:26 INFO - mozversion application_changeset: 61cc64967515bfa50f957d2f5946e1f20bc3a6c3 01:47:26 INFO - mozversion application_display_name: Nightly 01:47:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:47:26 INFO - mozversion application_name: Firefox 01:47:26 INFO - mozversion application_remotingname: firefox 01:47:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 01:47:26 INFO - mozversion application_vendor: Mozilla 01:47:26 INFO - mozversion application_version: 51.0a1 01:47:26 INFO - mozversion platform_buildid: 20160910001320 01:47:26 INFO - mozversion platform_changeset: 61cc64967515bfa50f957d2f5946e1f20bc3a6c3 01:47:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 01:47:26 INFO - mozversion platform_version: 51.0a1 01:47:26 INFO - using testdate: 1473497246 01:47:26 INFO - actual date: 1473497246 01:47:26 INFO - starting webserver on 'localhost:49282' 01:47:26 INFO - SUITE-START | Running 2 tests 01:47:26 INFO - TEST-START | dromaeo_css 01:47:26 INFO - operating with platform_type : mac_ 01:47:26 INFO - Initialising browser for dromaeo_css test... 01:47:26 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpitoijf/profile http://localhost:49282/getInfo.html) 01:47:28 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 01:47:28 INFO - PROCESS | 1676 | colorDepth:24 01:47:28 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 01:47:28 INFO - PROCESS | 1676 | __metrics 01:47:28 INFO - PROCESS | 1676 | 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" 01:47:28 INFO - PROCESS | 1676 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:47:29 INFO - TEST-INFO | 1676: exit 0 01:47:29 INFO - Browser initialized. 01:47:29 INFO - Running cycle 1/1 for dromaeo_css test... 01:47:29 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpitoijf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:47:41 INFO - PROCESS | 1679 | RSS: Main: 232591360 01:47:41 INFO - PROCESS | 1679 | 01:48:32 INFO - PROCESS | 1679 | 1473497312407 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] 01:50:44 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49282/tests/dromaeo/cssquery-ext.html) 01:50:45 INFO - PROCESS | 1679 | RSS: Main: 345206784 01:50:45 INFO - PROCESS | 1679 | 01:53:49 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-ext.html (next: http://localhost:49282/tests/dromaeo/cssquery-jquery.html) 01:53:49 INFO - PROCESS | 1679 | RSS: Main: 367689728 01:53:49 INFO - PROCESS | 1679 | 01:56:51 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49282/tests/dromaeo/cssquery-mootools.html) 01:56:52 INFO - PROCESS | 1679 | RSS: Main: 337747968 01:56:52 INFO - PROCESS | 1679 | 01:59:54 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49282/tests/dromaeo/cssquery-prototype.html) 01:59:55 INFO - PROCESS | 1679 | RSS: Main: 334659584 01:59:55 INFO - PROCESS | 1679 | 02:02:58 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49282/tests/dromaeo/cssquery-yui.html) 02:02:59 INFO - PROCESS | 1679 | RSS: Main: 327057408 02:02:59 INFO - PROCESS | 1679 | 02:06:02 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-yui.html (next: http://localhost:49282/tests/dromaeo/cssquery-dojo.html) 02:06:03 INFO - PROCESS | 1679 | RSS: Main: 404516864 02:06:03 INFO - PROCESS | 1679 | 02:06:03 INFO - PROCESS | 1679 | __start_tp_report 02:06:03 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 02:06:03 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 02:06:03 INFO - PROCESS | 1679 | |i|pagename|runs| 02:06:03 INFO - PROCESS | 1679 | |0;dojo.html;2984.940301193976;3321.136504508827;3358.848851801669;3389.271306669066;3424.332255210235;5566.248556444879;5653.1237658162945;5718.399568045358;5744.9425505744985;5776.624519406241;5670.914936275959;5676.460736230062;5740.483356497914;5774.566907481934;5842.123681447774;5241.842744717665;5287.47125287472;5318.946810531884;5328.387235467912;5343.65266257692;5244.056069907415;5270.0513907496825;5278.9208161877605;5325.920111198316;5328.653637513549;5158.716270605115;5274.129768588178;5328.573714102882;5388.488093631118;5403.432639572848;4289.892752681177;5251;5273.367195936474;5309.946900531023;5315.867103322448;4943.950560494385;4972.279019542146;4992.375953005874;4998.400191976986;5023.8492845214705;5617.354004289518;5662.235598194232;5700.543956483471;5723.51350135239;5758.683272420015;6154.169187159721;6195.113685070928;6230.158928544633;6269.62382257066;6270.278917924452;6233.158523599302;6288.968555157241;6312.779052733133;6318.905216421757;6318.936810631927;5602.1036634138745;5714.79998200061;5739.913901291484;5755.510781583528;5818.738156782955;5271.578273738091;6272.874542509125;6287.874242515124;6329.556931014877;6334.809955701337;6176.413240742122;6187.597806142587;6193.783217587363;6205.782797602063;6212.4408803207025;4877.658563900563;6105.8843398869;6139.723712432862;6189.473894718999;6277.183966084471;6224.751009959641;6237.781677641261;6259.4679452246155;6270.561060725706;6273.404026617464;6237.064440333896;6258.998560230432;6271.247450305992;6283.340249273851;6299.433051025429;4868.659193856467;4979.726115063634;4980.05378977993;5011.223260394645;5044.520770526794;4441.378207051015;4463.732176069446;4478.686491945533;4518.525554816762;4560.087982403533;5680.971595141998;5694.288213973254;5700.714964251771;5701.2018317435595;5707.4577915098;6154.3845615438095;6312.368763123651;6326.652034138075;6335.366463353628;6339.904901426482;4704.811807527729;5549.223108764776;5640.802571909963;5667.744951477193;5732.197492351074;5408.810691625774;5438.646487978269;5444.210589464506;5447.1829225616475;5488.094464413327;6306.4639505642535;6325.082862984749;6325.588836725598;6331.0819931110655;6335.049742538564;6192.380761923771;6216.316205217332;6230;6272.80816644836;6301.8109456716375;6312.873742525032;6338.58799178052;6345.619262844244;6347.523935704933;6354.650494222862;6212.378762123752;6227.532935029982;6233.158523599437;6233.781817636361;6238.189035425547;6123.70969670409;6162.07568864656;6166.290876549254;6231.781887633911;6279.748810047547;4557.703749256288;6045.75816967334;6112.442689633789;6147.047207682816;6152.0156774915795;6121;6121.969390153021;6129.35641757609;6134.110553969738;6146.200993870768;5739.971300143473;5866.941330586639;5958.314793798769;5965.880682386415;5974.820755377345;6059.939400606114;6094.78668246627;6116.082587611894;6169.691515424301;6176.382361763787;5913.497352725067;5939.732712028055;5965.254343207099;5973.223480947449;5975.492083172805 02:06:03 INFO - PROCESS | 1679 | |1;ext.html;495.71744105859653;507.2543361258949;529.1744877990335;538.7360193505181;546.2543627947853;30227.942022029234;31071.11611048619;32136.35727285453;32165.19587010329;39512;40868.97827554305;42771;42870;42877.35683964735;42896;9573.952130239339;9790.216782657406;9793.020697930186;9798.804023919518;9810.70567882961;8719.912800872005;8862.291016718647;8896.599653015597;9518.857217141749;9651.876587150851;8883.156100170474;8958.148975847284;11749.823752643675;12021.51913923442;12102.939485302606;12105.334206618632;12149.02807775376;12181.817272740916;12184.20802647825;12185.634430967086;11578.536858525733;11776.941115294454;11863;11870.109741769402;11880.465379057963;27743.722562774317;32982.175445613815;33021;33098.50352244718;33138.6686133138;3266.8203248821305;3393.8472768725464;3475.5308033415417;3863.806809659534;4064.715469917107;30066;30387.97400215331;30572.54141187884;31296.687033129605;31490.685093149004;3193.4890417533084;3205.6794320567988;3213.678632136791;3228.6448490666007;3291.4733642617307;411341;414936;418206;419197;419621.90189049166;53965.73017134968;54358.64103397447;58893.70553147292;59173.112403313986;59193.70403147957;53838.19242711362;54991.725041374535;60823.69588152031;62032;62369;15528.99061560995;16522.339106435847;16525.421610243582;16536.338546458246;16542.338306467846;12786.61640150797;12872.613821585368;12880.484780608858;12893.161944473579;12895.742085158245;15105.773413398807;17156.485305440856;17179.570510737332;17204.31182752676;17221;5608.663480191201;5741.655500669973;6699.531032827753;6789.898151527731;6839.350261725128;872.8777971084053;941.9340646154703;998.8701468809008;1032.3031953431405;1074.4359211413914;28334.574981375295;29577.85211073974;29631.703682962896;29687.554686679716;30134.54798178029;990.0347161517462;1017.4505766885947;1060.6923992042375;1064.2816099133054;1077.1974878715407;14093.929530352283;15818.887089356851;15946.760798588031;16224.107674077803;16243.675126497641;517496.412517935;519078;519491;520422;522877;48917.51082489201;48997.51002490001;49014;55283.170752438746;55506.88986220334;1233.3463264469844;1258.7419578986285;1262.0849883834146;1329.2622594459974;1341.5103487227286;29798.851005744833;30164.69835301619;30387.848060759556;30816.229594259425;30952.53571196479;48245.41668541483;48948.51051489582;48983.26525102055;49039.75480122577;49086.75456622694;428498;429171;432947.8352608217;434305;435367;24051;24518.75481245236;24599;24602.876985614956;25369.492610147325;45745;46233.306500403196;46521.53478465307;47199.29201062056;47326.05347893092;37653.81173094227;37811.865644030724;38019.86312968828;38276;39060.80469597634;37662.81168594139;38029.809850950565;38736.03159920912;39347.409788853765;39823.00442488845;33451.16372090717;36909.07727306839;37824.05439863916;37833;37878.2424351517;23473.413164670335;31999.040028799172;33674;33832.60176177081;33905.66094339025;24708.8881000359;24727.50544989126;24806;25195.622065669395;25476.872615636803 02:06:03 INFO - PROCESS | 1679 | |2;jquery.html;349.7743955148927;368.0981595092025;373.2702566482527;378.4663624289752;399.7801209334865;14949.701005979874;15066.924665376671;16198.271077801497;20459.386218413434;20936.37190884276;19231.230750769988;19739.802601973977;20157.39527814164;20389;20622.968851557438;4939.987302602975;5899.557533185018;6049.969750151243;6096.786612468564;6101.633901965896;5985.311689155737;6034.577579569432;6089.665068421235;6091.664958427285;6107.992181290082;5741.540676745871;5983.371745966676;6031.849203769897;6033.939660603403;6038.758449662008;5961;5985.940140598582;6011.489023433035;6015.849603759897;6032.909506357386;4958.826441074581;5162.22566615007;5198.8440346789275;5211.140161873324;5218.5825133989565;25161.748382516307;25344.438001159782;25461.49077018468;25505.362365940815;25626.743732562623;2190.7809219078126;2208;2271.2845453682203;2390.34265576966;2408.6146216605434;14527.98304118712;15055;15330.540083797503;15560.61098472536;16157.676846463122;1804;1854.2212270846278;1902.5624106455455;1907.6661584222845;1927.6915693488975;81592;103752;105054;105878;105944;10338.793224135476;10522.947385263025;10872.18458615607;10960.616378426876;11290.435478226056;13563.796543051862;13605.183688978694;13607.183568985893;13626.93186534061;13639.727205455836;7439.8140046499275;8238.588070596446;8513.233808957224;8514.106018868053;8781.07798681142;8286.46138001028;8569.400141990125;8646.6973655923;8817;8882.86675699865;5948.643081415043;6114.991026480581;6522.640839967421;6602.60384376939;6636.203655561267;1558.7428074367829;1800.9639807203782;1826.2514915052761;1840.7791065072277;1842.5218081680387;695.8260434891278;927.4759760735298;944.3342443577399;962.8363178259713;1067.6530127708527;16358.672826543403;19180.61638767217;22023.889880550494;22128.668069978965;22135.44661383478;760.7071277558069;931.3945935142076;996.7341476325026;1025.564135242519;1034.7930413917247;8899.822003559893;11470.311781293149;11593.420328983686;11609.825852612217;11750.823737643943;449650;461827;462268;466570;467222.63886805525;98869;100084;101688.98258314304;101814;103479.48260258802;962.5668449197889;997.516184700106;1003.2375394699934;1022.6114076650974;1024.4826362686795;14017.299135043411;19277;19420.4173874784;19476;19506.512337191118;46870;47664.570062898165;48157;48779.51220487892;48823.0235395283;63775.04337435035;64895;65529.017064743115;65648.34351656423;66927.66536167289;6504.38208370204;6774.86450270982;7008.894866577108;7852.450328476952;8346.916530834857;12402.027666291715;12414.503419863104;13179;13531.458741650224;13531.526396576073;11041.83437248458;11153.721156970816;11594.072474202212;11630.709232268922;11647.126465514952;11140.610078647209;11331;11401.660676663774;11639;11751.706207344885;9277.536123193679;11160.888391115985;11644.825327620261;11720.41397930083;11988.76022479563;7191.7842464726145;7347.044884165024;7390.852182956418;7390.96304518474;7397.112346518452;8320.12638672955;9963.601455941971;11325.546978120783;11379.260348077349;11564.132690048113 02:06:03 INFO - PROCESS | 1679 | |3;mootools.html;1480.6594483268848;1521.4598817419815;1651.9008859468424;1717.5448506145865;1847.8059803720616;7287.672054757535;8869.02440731519;8885.200331970129;8887.866681999767;8889.777755556115;6496.480281577463;8009.519428834259;8010.239027292412;8119.228673276044;8711.869321960176;2146.6350720377495;2185.2024011235862;2324.651302304651;2402.9759702403016;2403.387136280246;2388.414838364603;2408.4460574067975;2410.795082417988;2413.083028449196;2415.830891837572;2373.22870067228;2423.212455951814;2426.3206302235403;2432.1730611592056;2432.6107822748363;2384.344305316043;2388.892499837503;2397.4725560376687;2403.6755038069987;2413.227767114524;2363.5981883079917;2372.6441033845063;2384.3800611841048;2389.8446600971056;2390.3546042568582;8760.167784060504;8956.462612243286;8956.955215223883;8973.775655608662;8980.281577473786;5264.894702105975;5422.240886275925;5434.64674796137;5442.292501974757;5443.891122177534;3511.5848649504546;3624.981875090634;3724.255148970217;3734.8879533614036;3754.080250338657;1043.9060484556426;1094.480121942079;1102.9503672334683;1113.4877956140185;1114.2033446086007;11692.356920369377;11724.296542207494;11734;11739.823902641467;11760.941195294054;4728.929066064012;4730.62155027597;4834.226523756252;4941.530554597308;4973.303737476756;9132;9198.172164505228;9199.586018629177;9202.125798049176;9207.63169473227;5268.4994925482015;5270.947290527122;5271.42014378418;5274.393444753902;5276.92084618731;4777.402824646919;4886.2426323919135;4951.158303088484;4952.727599982036;4960.330355402047;3086;3545.7872527648424;3646.7447278690765;3658.231771328051;3862.343401621731;1166.2943918929095;1167.217963964146;1168.842845582884;1175.8295047218105;1177.8939895409283;842.6671464771381;861.1603686405705;885.5926273913943;893.3791015244343;956.5552018311519;5659.518940890071;5670.603057785916;5941.792037278675;6075.939240607626;6079.118527813443;907.0611916666219;936.5270538378076;944.900785417535;948.7343543807607;986.4130842149053;3924.980375098163;3947.0921688011917;4014.3577027675433;4106.897327566835;4109.404136400265;9117;10627.574897004188;10848.349099054081;10960.616378426717;10977.83533247002;10724.195685323632;10908.23642345044;10909.963553462409;10931.45342732876;10946.233763636468;8139.267465928095;8268.297194738514;8274.917250827535;8286;8301.916980830236;5811.912821307768;5881.411858814085;5921.467067963732;6002.549808764273;6258.43674069336;7924.9207507926485;9915.950420247853;10187.694369168938;10191.439470829175;10246.231532634933;10079.89920100819;10204.234682398997;10245.538950747085;10260.58957641686;10267.94866025695;7428.702851885864;7504.512206706765;7597.582132982739;7618.961905190438;7623.313901748869;8218.876716849372;8244.093149753402;8396.748097557083;8401.201885820838;8404.915950840657;7469.253074692488;7547.547147171187;7558.470907036454;7584.54492730438;7744.806379840549;7373.671871102865;7387.335139837441;7389.519681220703;7392.55644661322;7433.8884916727375;6870.587764734132;6902.482313826383;6956.582605043714;6965.6168910708375;6969.16370035599;5001.42483614389;5028.472010438852;5082.034413461431;5101.387833460009;5162.148245539444;4938.851834444972;4953.801847926186;5097.7706003229205;5226.843194704165;5247.868803279948 02:06:03 INFO - PROCESS | 1679 | |4;prototype.html;263.7823795368821;300.87664057736333;306.48357517582866;311.1396987330032;312.1042607715856;5391.757370918308;5400.675959442437;5406.864828379293;5419.051665958456;5464.9180262296095;4307.741535507872;5170.121079416499;5304.20436934459;5428.83713488596;5455.18172274158;3303.867845286192;3436.4673475611194;3898.4601082572367;3983.4423180754716;4041.8383264669524;3653.2876089162623;3728.117300763111;3985.760854348734;3998.480197574311;4047.392891066334;4001.639852413297;4008.6793056555407;4032.5967403259588;4044.9191016179657;4054.858079967202;3173.1591128351006;3963.6234557717116;4001.159756451149;4028.295048366524;4053.3717273822604;2911.708829117071;3117.485614873543;3351.8659253629826;3468.7047305060296;3508.9824550877083;5127.307813445174;5164.476479438575;5176.618755372533;5183.870403239912;5191.974040129813;3775.4714339992424;4301.558528461499;4482.372467854503;4616.792244349012;4658.790354434059;4911.680740751875;4931.284963680247;5053.545180933696;5135.6918584885025;5426.650818542315;3087.8764849405998;3580.4629305604108;3879.5451705610394;3894.4547763313158;3895.8636447724193;4861.902761944777;5589.105743081088;5902.055671092605;5910.793122240744;5917.467427931507;3558.8646811913163;3833.750806197588;3846.1538461538416;3846.538415390171;3858.5562660294427;3892.3772196449;3915.275674000319;3919.568847426781;3922.882313530599;3922.9607703922597;4031.879043628696;4239.51245606753;4473.642108631302;4493.573110554492;4526.932096018562;4404.757738324423;5089.236614507853;5366.33761595311;5574.581906356958;5764.942350576441;994.557421947241;1126.9436528173721;1313.6321829887647;1323.4970711129708;1323.9867601324056;750.8586947839253;753.3709352690565;800.371708209053;807.289585165051;813.8128230507017;4978.751062446863;5147.382314122255;5157.638965272395;5184.481551844785;5188.403333616682;5684.317881854204;5712.143553345097;5745.2818397700285;5852.414758524199;5879.441453061952;4785.425748910153;4925.694690906913;5124.1032819257225;5173.689578625219;5360.84741780519;2884.336602581418;3059.8372618404865;3078.2304423894025;3108.7357574606413;3124.125524685216;5889.793857215062;5922.881542369215;5979.282486101695;6003.189569408161;6003.729832157564;5225.413420356947;5988.341282459013;6013.248343957005;6026.969865150646;6109.541784366191;5156.942826720513;5618.061768689796;5947.256592925971;6100.553187449077;6117.776444710987;5560.860978475571;5796.362400135898;5855.7364918579615;5907.084401917709;5973.640412037134;4860.76050607107;5031.396232452129;5774.307083150049;5833.241678581757;5845.901672113787;5257.710825904612;5417.377001644703;5875.824835032925;6103.84740381494;6218.347073557211;3249.3014001989686;3487.3722729907845;3786.6213378661914;3988.641022308006;4011.3581826907553;5769.480746732815;5777.942220577908;5815.33123690764;5829.329627092939;5859.425750292233;5270.84041510867;5712.6001179917;5715.828525144252;5739.311282646109;5742.770289188553;4480.14877173336;5444.945550544602;5596;5671.149327600893;5761.740721667619;5013.696438925978;5058.1401161803315;5508.063629183129;5556.638818476786;5614.9719251403485;4251.407560611278;4495.698406946102;4616.953830461653;5681.744321505625;5734.799282025109;4314.525402205818;5535.7785688572;5536;5555.2500412445415;5616.241807356118 02:06:03 INFO - PROCESS | 1679 | |5;yui.html;686.7390391651173;703.1843062048024;721.9494635375522;731.1737736357918;769.6267310354481;14998.850011499882;16157.838421615781;16196.271167797448;16208.75686864698;16222.621077208452;14585.270736463188;14789.630259243524;14794.408223671067;14809.925950370232;14814.259287035633;687.5290426058141;708.7483943200141;712.2948281201599;853.9615717292721;878.7846977490524;854.3080105114846;864.511550973701;873.4453621950043;873.6592728835727;875.1773333066928;914.3462424366577;914.5381582300929;914.6935776514875;918.7565295196778;919.7194855569048;721.3039416962604;907.5689047702355;908.0374802709116;911.589784596934;912.717057712108;548.8326060551531;550.8843142939987;551.5973339462175;552.5331095224528;667.7329068372641;14571.562853114423;14678.779818302733;14692.485762998243;14700.632484187874;14708.632284192874;13508.594742157751;13834.515791947335;13982.72034559303;13990.86009139916;14064.859351406458;10459;12340.96755168091;12403.565875194325;12508.937455312665;12838.101332906701;4050.675945924319;4133.772642504661;4206.221848957953;4222.366645003243;4294.484661840568;107582.46208768984;107699;108668;108943.37396382257;108983.45508272487;10451.216158788122;10578;10978.451077446096;11006.229563930448;11318.26431281964;21559.56880862374;21784.891075544838;21972.670409943865;22013.339599812032;22059.448513786963;7319.853602927911;7601.296114628866;7624.390048796083;7787.649555770004;7825.608719563999;4268.365399904635;4383.408239887638;4386.758728269976;4412.426384569985;4515.819367225275;3355.3624811285777;3513.9648603514147;3514.103903504641;3516.367053930266;3524.8766293179615;525.905337039329;643.5945354426775;647.6696884588894;656.8883289840738;665.0356982375478;540.926974858397;595.1043679262682;611.535233222745;623.797265888579;628.5788521690479;7997.400194985399;8388.203120703523;8705.173008564303;9161.024258225727;9197.310201734897;576.5113645915197;589.3399392680226;612.5085417009595;619.1363048547341;629.8488362792988;4317.7193482423545;4354.259775838115;4393.1872603568445;4395.362672412482;4456.614106316731;132441.04015319335;135258.32370838083;141953;146601;147244;110706;121707;122971;125514;128894;70522.64738676274;72849;89052;91424;95060.99999999862;9505.619775208914;9532.714018579454;9592.808143836945;9698.6120555177;9731.318807683394;56493.71753141208;56590;57515.42484575268;60303.39696602978;62579.68710156602;47812.5218747822;66429;68816.24735010543;78543;90060.54969725372;10655.89344106549;13387.062905596515;13592.456301748214;13767.931160344135;13875.028747987948;13753.174809511462;14167.929160354131;14558.563243102724;14561.199134047729;15375.923120384326;12056.51773929033;13569.253691047088;13826.377812998238;14096.718065638424;14101.294935253401;13170.209787412787;13589.116707413987;13876.930615346859;13884.236366999912;13931.164130152223;13165.736685266049;13231.073824832169;13327.933360333136;13416.597502074954;13442.529511467443;8987.595558200028;10467.267291289842;10736.30214036085;11350.262232954832;11534.13493987971;9815.803683926424;11249.212555121063;11433.59982400612;11485.425728713364;11499.597514086967 02:06:03 INFO - PROCESS | 1679 | __end_tp_report 02:06:03 INFO - PROCESS | 1679 | __start_cc_report 02:06:03 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,1424 02:06:03 INFO - PROCESS | 1679 | __end_cc_report 02:06:03 INFO - PROCESS | 1679 | __startTimestamp1473498363075__endTimestamp 02:06:03 INFO - PROCESS | 1679 | 02:06:03 INFO - PROCESS | 1679 | ------- Summary: start ------- 02:06:03 INFO - PROCESS | 1679 | Number of tests: 6 02:06:03 INFO - PROCESS | 1679 | 02:06:03 INFO - PROCESS | 1679 | [#0] dojo.html Cycles:165 Average:5726.80 Median:5958.31 stddev:667.38 (11.2%) stddev-sans-first:633.82 02:06:03 INFO - PROCESS | 1679 | Values: 2984.9 3321.1 3358.8 3389.3 3424.3 5566.2 5653.1 5718.4 5744.9 5776.6 5670.9 5676.5 5740.5 5774.6 5842.1 5241.8 5287.5 5318.9 5328.4 5343.7 5244.1 5270.1 5278.9 5325.9 5328.7 5158.7 5274.1 5328.6 5388.5 5403.4 4289.9 5251.0 5273.4 5309.9 5315.9 4944.0 4972.3 4992.4 4998.4 5023.8 5617.4 5662.2 5700.5 5723.5 5758.7 6154.2 6195.1 6230.2 6269.6 6270.3 6233.2 6289.0 6312.8 6318.9 6318.9 5602.1 5714.8 5739.9 5755.5 5818.7 5271.6 6272.9 6287.9 6329.6 6334.8 6176.4 6187.6 6193.8 6205.8 6212.4 4877.7 6105.9 6139.7 6189.5 6277.2 6224.8 6237.8 6259.5 6270.6 6273.4 6237.1 6259.0 6271.2 6283.3 6299.4 4868.7 4979.7 4980.1 5011.2 5044.5 4441.4 4463.7 4478.7 4518.5 4560.1 5681.0 5694.3 5700.7 5701.2 5707.5 6154.4 6312.4 6326.7 6335.4 6339.9 4704.8 5549.2 5640.8 5667.7 5732.2 5408.8 5438.6 5444.2 5447.2 5488.1 6306.5 6325.1 6325.6 6331.1 6335.0 6192.4 6216.3 6230.0 6272.8 6301.8 6312.9 6338.6 6345.6 6347.5 6354.7 6212.4 6227.5 6233.2 6233.8 6238.2 6123.7 6162.1 6166.3 6231.8 6279.7 4557.7 6045.8 6112.4 6147.0 6152.0 6121.0 6122.0 6129.4 6134.1 6146.2 5740.0 5866.9 5958.3 5965.9 5974.8 6059.9 6094.8 6116.1 6169.7 6176.4 5913.5 5939.7 5965.3 5973.2 5975.5 02:06:03 INFO - PROCESS | 1679 | 02:06:03 INFO - PROCESS | 1679 | [#1] ext.html Cycles:180 Average:59903.97 Median:3434.69 stddev:121694.21 (3543.1%) stddev-sans-first:121953.85 02:06:03 INFO - PROCESS | 1679 | Values: 495.7 507.3 529.2 538.7 546.3 30227.9 31071.1 32136.4 32165.2 39512.0 40869.0 42771.0 42870.0 42877.4 42896.0 9574.0 9790.2 9793.0 9798.8 9810.7 8719.9 8862.3 8896.6 9518.9 9651.9 8883.2 8958.1 11749.8 12021.5 12102.9 12105.3 12149.0 12181.8 12184.2 12185.6 11578.5 11776.9 11863.0 11870.1 11880.5 27743.7 32982.2 33021.0 33098.5 33138.7 3266.8 3393.8 3475.5 3863.8 4064.7 30066.0 30388.0 30572.5 31296.7 31490.7 3193.5 3205.7 3213.7 3228.6 3291.5 411341.0 414936.0 418206.0 419197.0 419621.9 53965.7 54358.6 58893.7 59173.1 59193.7 53838.2 54991.7 60823.7 62032.0 62369.0 15529.0 16522.3 16525.4 16536.3 16542.3 12786.6 12872.6 12880.5 12893.2 12895.7 15105.8 17156.5 17179.6 17204.3 17221.0 5608.7 5741.7 6699.5 6789.9 6839.4 872.9 941.9 998.9 1032.3 1074.4 28334.6 29577.9 29631.7 29687.6 30134.5 990.0 1017.5 1060.7 1064.3 1077.2 14093.9 15818.9 15946.8 16224.1 16243.7 517496.4 519078.0 519491.0 520422.0 522877.0 48917.5 48997.5 49014.0 55283.2 55506.9 1233.3 1258.7 1262.1 1329.3 1341.5 29798.9 30164.7 30387.8 30816.2 30952.5 48245.4 48948.5 48983.3 49039.8 49086.8 428498.0 429171.0 432947.8 434305.0 435367.0 24051.0 24518.8 24599.0 24602.9 25369.5 45745.0 46233.3 46521.5 47199.3 47326.1 37653.8 37811.9 38019.9 38276.0 39060.8 37662.8 38029.8 38736.0 39347.4 39823.0 33451.2 36909.1 37824.1 37833.0 37878.2 23473.4 31999.0 33674.0 33832.6 33905.7 24708.9 24727.5 24806.0 25195.6 25476.9 02:06:03 INFO - PROCESS | 1679 | 02:06:03 INFO - PROCESS | 1679 | [#2] jquery.html Cycles:180 Average:29519.56 Median:25253.09 stddev:77059.90 (305.2%) stddev-sans-first:77244.95 02:06:03 INFO - PROCESS | 1679 | Values: 349.8 368.1 373.3 378.5 399.8 14949.7 15066.9 16198.3 20459.4 20936.4 19231.2 19739.8 20157.4 20389.0 20623.0 4940.0 5899.6 6050.0 6096.8 6101.6 5985.3 6034.6 6089.7 6091.7 6108.0 5741.5 5983.4 6031.8 6033.9 6038.8 5961.0 5985.9 6011.5 6015.8 6032.9 4958.8 5162.2 5198.8 5211.1 5218.6 25161.7 25344.4 25461.5 25505.4 25626.7 2190.8 2208.0 2271.3 2390.3 2408.6 14528.0 15055.0 15330.5 15560.6 16157.7 1804.0 1854.2 1902.6 1907.7 1927.7 81592.0 103752.0 105054.0 105878.0 105944.0 10338.8 10522.9 10872.2 10960.6 11290.4 13563.8 13605.2 13607.2 13626.9 13639.7 7439.8 8238.6 8513.2 8514.1 8781.1 8286.5 8569.4 8646.7 8817.0 8882.9 5948.6 6115.0 6522.6 6602.6 6636.2 1558.7 1801.0 1826.3 1840.8 1842.5 695.8 927.5 944.3 962.8 1067.7 16358.7 19180.6 22023.9 22128.7 22135.4 760.7 931.4 996.7 1025.6 1034.8 8899.8 11470.3 11593.4 11609.8 11750.8 449650.0 461827.0 462268.0 466570.0 467222.6 98869.0 100084.0 101689.0 101814.0 103479.5 962.6 997.5 1003.2 1022.6 1024.5 14017.3 19277.0 19420.4 19476.0 19506.5 46870.0 47664.6 48157.0 48779.5 48823.0 63775.0 64895.0 65529.0 65648.3 66927.7 6504.4 6774.9 7008.9 7852.5 8346.9 12402.0 12414.5 13179.0 13531.5 13531.5 11041.8 11153.7 11594.1 11630.7 11647.1 11140.6 11331.0 11401.7 11639.0 11751.7 9277.5 11160.9 11644.8 11720.4 11988.8 7191.8 7347.0 7390.9 7391.0 7397.1 8320.1 9963.6 11325.5 11379.3 11564.1 02:06:03 INFO - PROCESS | 1679 | 02:06:03 INFO - PROCESS | 1679 | [#3] mootools.html Cycles:180 Average:5693.34 Median:5055.25 stddev:3164.83 (62.6%) stddev-sans-first:3157.87 02:06:03 INFO - PROCESS | 1679 | Values: 1480.7 1521.5 1651.9 1717.5 1847.8 7287.7 8869.0 8885.2 8887.9 8889.8 6496.5 8009.5 8010.2 8119.2 8711.9 2146.6 2185.2 2324.7 2403.0 2403.4 2388.4 2408.4 2410.8 2413.1 2415.8 2373.2 2423.2 2426.3 2432.2 2432.6 2384.3 2388.9 2397.5 2403.7 2413.2 2363.6 2372.6 2384.4 2389.8 2390.4 8760.2 8956.5 8957.0 8973.8 8980.3 5264.9 5422.2 5434.6 5442.3 5443.9 3511.6 3625.0 3724.3 3734.9 3754.1 1043.9 1094.5 1103.0 1113.5 1114.2 11692.4 11724.3 11734.0 11739.8 11760.9 4728.9 4730.6 4834.2 4941.5 4973.3 9132.0 9198.2 9199.6 9202.1 9207.6 5268.5 5270.9 5271.4 5274.4 5276.9 4777.4 4886.2 4951.2 4952.7 4960.3 3086.0 3545.8 3646.7 3658.2 3862.3 1166.3 1167.2 1168.8 1175.8 1177.9 842.7 861.2 885.6 893.4 956.6 5659.5 5670.6 5941.8 6075.9 6079.1 907.1 936.5 944.9 948.7 986.4 3925.0 3947.1 4014.4 4106.9 4109.4 9117.0 10627.6 10848.3 10960.6 10977.8 10724.2 10908.2 10910.0 10931.5 10946.2 8139.3 8268.3 8274.9 8286.0 8301.9 5811.9 5881.4 5921.5 6002.5 6258.4 7924.9 9916.0 10187.7 10191.4 10246.2 10079.9 10204.2 10245.5 10260.6 10267.9 7428.7 7504.5 7597.6 7619.0 7623.3 8218.9 8244.1 8396.7 8401.2 8404.9 7469.3 7547.5 7558.5 7584.5 7744.8 7373.7 7387.3 7389.5 7392.6 7433.9 6870.6 6902.5 6956.6 6965.6 6969.2 5001.4 5028.5 5082.0 5101.4 5162.1 4938.9 4953.8 5097.8 5226.8 5247.9 02:06:03 INFO - PROCESS | 1679 | 02:06:03 INFO - PROCESS | 1679 | [#4] prototype.html Cycles:180 Average:4519.78 Median:5141.54 stddev:1438.82 (28.0%) stddev-sans-first:1406.95 02:06:03 INFO - PROCESS | 1679 | Values: 263.8 300.9 306.5 311.1 312.1 5391.8 5400.7 5406.9 5419.1 5464.9 4307.7 5170.1 5304.2 5428.8 5455.2 3303.9 3436.5 3898.5 3983.4 4041.8 3653.3 3728.1 3985.8 3998.5 4047.4 4001.6 4008.7 4032.6 4044.9 4054.9 3173.2 3963.6 4001.2 4028.3 4053.4 2911.7 3117.5 3351.9 3468.7 3509.0 5127.3 5164.5 5176.6 5183.9 5192.0 3775.5 4301.6 4482.4 4616.8 4658.8 4911.7 4931.3 5053.5 5135.7 5426.7 3087.9 3580.5 3879.5 3894.5 3895.9 4861.9 5589.1 5902.1 5910.8 5917.5 3558.9 3833.8 3846.2 3846.5 3858.6 3892.4 3915.3 3919.6 3922.9 3923.0 4031.9 4239.5 4473.6 4493.6 4526.9 4404.8 5089.2 5366.3 5574.6 5764.9 994.6 1126.9 1313.6 1323.5 1324.0 750.9 753.4 800.4 807.3 813.8 4978.8 5147.4 5157.6 5184.5 5188.4 5684.3 5712.1 5745.3 5852.4 5879.4 4785.4 4925.7 5124.1 5173.7 5360.8 2884.3 3059.8 3078.2 3108.7 3124.1 5889.8 5922.9 5979.3 6003.2 6003.7 5225.4 5988.3 6013.2 6027.0 6109.5 5156.9 5618.1 5947.3 6100.6 6117.8 5560.9 5796.4 5855.7 5907.1 5973.6 4860.8 5031.4 5774.3 5833.2 5845.9 5257.7 5417.4 5875.8 6103.8 6218.3 3249.3 3487.4 3786.6 3988.6 4011.4 5769.5 5777.9 5815.3 5829.3 5859.4 5270.8 5712.6 5715.8 5739.3 5742.8 4480.1 5444.9 5596.0 5671.1 5761.7 5013.7 5058.1 5508.1 5556.6 5615.0 4251.4 4495.7 4617.0 5681.7 5734.8 4314.5 5535.8 5536.0 5555.3 5616.2 02:06:03 INFO - PROCESS | 1679 | 02:06:03 INFO - PROCESS | 1679 | [#5] yui.html Cycles:180 Average:22963.35 Median:4214.29 stddev:35967.16 (853.5%) stddev-sans-first:36029.17 02:06:03 INFO - PROCESS | 1679 | Values: 686.7 703.2 721.9 731.2 769.6 14998.9 16157.8 16196.3 16208.8 16222.6 14585.3 14789.6 14794.4 14809.9 14814.3 687.5 708.7 712.3 854.0 878.8 854.3 864.5 873.4 873.7 875.2 914.3 914.5 914.7 918.8 919.7 721.3 907.6 908.0 911.6 912.7 548.8 550.9 551.6 552.5 667.7 14571.6 14678.8 14692.5 14700.6 14708.6 13508.6 13834.5 13982.7 13990.9 14064.9 10459.0 12341.0 12403.6 12508.9 12838.1 4050.7 4133.8 4206.2 4222.4 4294.5 107582.5 107699.0 108668.0 108943.4 108983.5 10451.2 10578.0 10978.5 11006.2 11318.3 21559.6 21784.9 21972.7 22013.3 22059.4 7319.9 7601.3 7624.4 7787.6 7825.6 4268.4 4383.4 4386.8 4412.4 4515.8 3355.4 3514.0 3514.1 3516.4 3524.9 525.9 643.6 647.7 656.9 665.0 540.9 595.1 611.5 623.8 628.6 7997.4 8388.2 8705.2 9161.0 9197.3 576.5 589.3 612.5 619.1 629.8 4317.7 4354.3 4393.2 4395.4 4456.6 132441.0 135258.3 141953.0 146601.0 147244.0 110706.0 121707.0 122971.0 125514.0 128894.0 70522.6 72849.0 89052.0 91424.0 95061.0 9505.6 9532.7 9592.8 9698.6 9731.3 56493.7 56590.0 57515.4 60303.4 62579.7 47812.5 66429.0 68816.2 78543.0 90060.5 10655.9 13387.1 13592.5 13767.9 13875.0 13753.2 14167.9 14558.6 14561.2 15375.9 12056.5 13569.3 13826.4 14096.7 14101.3 13170.2 13589.1 13876.9 13884.2 13931.2 13165.7 13231.1 13327.9 13416.6 13442.5 8987.6 10467.3 10736.3 11350.3 11534.1 9815.8 11249.2 11433.6 11485.4 11499.6 02:06:03 INFO - PROCESS | 1679 | -------- Summary: end -------- 02:06:03 INFO - PROCESS | 1679 | 02:06:03 INFO - TEST-INFO | 1679: exit 0 02:06:03 INFO - TEST-OK | dromaeo_css | took 1117218ms 02:06:03 INFO - TEST-START | kraken 02:06:03 INFO - operating with platform_type : mac_ 02:06:03 INFO - Initialising browser for kraken test... 02:06:03 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpWMFHGh/profile http://localhost:49282/getInfo.html) 02:06:05 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 02:06:05 INFO - PROCESS | 1683 | colorDepth:24 02:06:05 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 02:06:05 INFO - PROCESS | 1683 | __metrics 02:06:06 INFO - PROCESS | 1683 | 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" 02:06:06 INFO - PROCESS | 1683 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:06:06 INFO - TEST-INFO | 1683: exit 0 02:06:06 INFO - Browser initialized. 02:06:06 INFO - Running cycle 1/1 for kraken test... 02:06:06 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpWMFHGh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:06:18 INFO - PROCESS | 1684 | RSS: Main: 232767488 02:06:18 INFO - PROCESS | 1684 | 02:06:21 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-beat-detection) 02:06:22 INFO - PROCESS | 1684 | RSS: Main: 293232640 02:06:22 INFO - PROCESS | 1684 | 02:06:25 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-dft) 02:06:25 INFO - PROCESS | 1684 | RSS: Main: 289816576 02:06:25 INFO - PROCESS | 1684 | 02:06:29 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-fft) 02:06:29 INFO - PROCESS | 1684 | RSS: Main: 375693312 02:06:29 INFO - PROCESS | 1684 | 02:06:31 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-oscillator) 02:06:32 INFO - PROCESS | 1684 | RSS: Main: 281337856 02:06:32 INFO - PROCESS | 1684 | 02:06:34 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:06:35 INFO - PROCESS | 1684 | RSS: Main: 285204480 02:06:35 INFO - PROCESS | 1684 | 02:06:38 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-darkroom) 02:06:38 INFO - PROCESS | 1684 | RSS: Main: 322580480 02:06:38 INFO - PROCESS | 1684 | 02:06:41 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-desaturate) 02:06:42 INFO - PROCESS | 1684 | RSS: Main: 323432448 02:06:42 INFO - PROCESS | 1684 | 02:06:45 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49282/tests/kraken/driver.html?testName=json-parse-financial) 02:06:45 INFO - PROCESS | 1684 | RSS: Main: 322310144 02:06:45 INFO - PROCESS | 1684 | 02:06:47 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49282/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:06:48 INFO - PROCESS | 1684 | RSS: Main: 298487808 02:06:48 INFO - PROCESS | 1684 | 02:06:50 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:06:50 INFO - PROCESS | 1684 | RSS: Main: 303968256 02:06:50 INFO - PROCESS | 1684 | 02:06:52 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:06:53 INFO - PROCESS | 1684 | RSS: Main: 303722496 02:06:53 INFO - PROCESS | 1684 | 02:06:56 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:06:57 INFO - PROCESS | 1684 | RSS: Main: 321351680 02:06:57 INFO - PROCESS | 1684 | 02:07:00 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:07:00 INFO - PROCESS | 1684 | RSS: Main: 317673472 02:07:00 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49282/tests/kraken/driver.html?testName=ai-astar) 02:07:02 INFO - PROCESS | 1684 | RSS: Main: 361324544 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | __start_tp_report 02:07:02 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 02:07:02 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 02:07:02 INFO - PROCESS | 1684 | |i|pagename|runs| 02:07:02 INFO - PROCESS | 1684 | |0;ai-astar;113;113;118;162;168;114;113;112;115;125 02:07:02 INFO - PROCESS | 1684 | |1;audio-beat-detection;152;109;129;158;103;128;127;106;161;107 02:07:02 INFO - PROCESS | 1684 | |2;audio-dft;184;187;185;186;184;183;184;184;186;182 02:07:02 INFO - PROCESS | 1684 | |3;audio-fft;80;74;76;153;57;58;57;66;136;58 02:07:02 INFO - PROCESS | 1684 | |4;audio-oscillator;82;82;139;70;75;82;115;68;70;114 02:07:02 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;88;85;91;130;125;85;90;86;89;85 02:07:02 INFO - PROCESS | 1684 | |6;imaging-darkroom;97;96;96;95;96;100;148;162;111;97 02:07:02 INFO - PROCESS | 1684 | |7;imaging-desaturate;84;84;86;85;83;83;83;85;95;101 02:07:02 INFO - PROCESS | 1684 | |8;json-parse-financial;56;55;57;56;54;55;54;54;54;54 02:07:02 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;64;64;72;99;109;113;72;71;70;71 02:07:02 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;86;88;86;84;83;86;85;88;87;100 02:07:02 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;137;153;155;150;139;140;158;148;185;141 02:07:02 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;168;169;169;168;169;171;172;171;168;169 02:07:02 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;67;66;66;67;67;67;67;68;67;66 02:07:02 INFO - PROCESS | 1684 | __end_tp_report 02:07:02 INFO - PROCESS | 1684 | __start_cc_report 02:07:02 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,1992 02:07:02 INFO - PROCESS | 1684 | __end_cc_report 02:07:02 INFO - PROCESS | 1684 | __startTimestamp1473498422673__endTimestamp 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | ------- Summary: start ------- 02:07:02 INFO - PROCESS | 1684 | Number of tests: 14 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:125.30 Median:116.50 stddev:21.31 (18.3%) stddev-sans-first:22.14 02:07:02 INFO - PROCESS | 1684 | Values: 113.0 113.0 118.0 162.0 168.0 114.0 113.0 112.0 115.0 125.0 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:128.00 Median:128.50 stddev:22.31 (17.4%) stddev-sans-first:21.90 02:07:02 INFO - PROCESS | 1684 | Values: 152.0 109.0 129.0 158.0 103.0 128.0 127.0 106.0 161.0 107.0 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:184.50 Median:184.50 stddev:1.51 (0.8%) stddev-sans-first:1.59 02:07:02 INFO - PROCESS | 1684 | Values: 184.0 187.0 185.0 186.0 184.0 183.0 184.0 184.0 186.0 182.0 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:81.50 Median:62.00 stddev:34.51 (55.7%) stddev-sans-first:36.60 02:07:02 INFO - PROCESS | 1684 | Values: 80.0 74.0 76.0 153.0 57.0 58.0 57.0 66.0 136.0 58.0 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:72.50 stddev:24.27 (33.5%) stddev-sans-first:25.58 02:07:02 INFO - PROCESS | 1684 | Values: 82.0 82.0 139.0 70.0 75.0 82.0 115.0 68.0 70.0 114.0 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:95.40 Median:87.00 stddev:17.10 (19.7%) stddev-sans-first:17.92 02:07:02 INFO - PROCESS | 1684 | Values: 88.0 85.0 91.0 130.0 125.0 85.0 90.0 86.0 89.0 85.0 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:109.80 Median:96.00 stddev:24.49 (25.5%) stddev-sans-first:25.54 02:07:02 INFO - PROCESS | 1684 | Values: 97.0 96.0 96.0 95.0 96.0 100.0 148.0 162.0 111.0 97.0 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:86.90 Median:84.50 stddev:6.10 (7.2%) stddev-sans-first:6.38 02:07:02 INFO - PROCESS | 1684 | Values: 84.0 84.0 86.0 85.0 83.0 83.0 83.0 85.0 95.0 101.0 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:1.10 (2.0%) stddev-sans-first:1.09 02:07:02 INFO - PROCESS | 1684 | Values: 56.0 55.0 57.0 56.0 54.0 55.0 54.0 54.0 54.0 54.0 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:80.50 Median:71.00 stddev:18.83 (26.5%) stddev-sans-first:19.00 02:07:02 INFO - PROCESS | 1684 | Values: 64.0 64.0 72.0 99.0 109.0 113.0 72.0 71.0 70.0 71.0 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:86.00 stddev:4.74 (5.5%) stddev-sans-first:5.00 02:07:02 INFO - PROCESS | 1684 | Values: 86.0 88.0 86.0 84.0 83.0 86.0 85.0 88.0 87.0 100.0 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:150.60 Median:151.50 stddev:14.12 (9.3%) stddev-sans-first:14.09 02:07:02 INFO - PROCESS | 1684 | Values: 137.0 153.0 155.0 150.0 139.0 140.0 158.0 148.0 185.0 141.0 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.40 Median:169.00 stddev:1.43 (0.8%) stddev-sans-first:1.42 02:07:02 INFO - PROCESS | 1684 | Values: 168.0 169.0 169.0 168.0 169.0 171.0 172.0 171.0 168.0 169.0 02:07:02 INFO - PROCESS | 1684 | 02:07:02 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.63 (0.9%) stddev-sans-first:0.67 02:07:02 INFO - PROCESS | 1684 | Values: 67.0 66.0 66.0 67.0 67.0 67.0 67.0 68.0 67.0 66.0 02:07:02 INFO - PROCESS | 1684 | -------- Summary: end -------- 02:07:02 INFO - PROCESS | 1684 | 02:07:03 INFO - TEST-INFO | 1684: exit 0 02:07:03 INFO - TEST-OK | kraken | took 59903ms 02:07:03 INFO - SUITE-END | took 1177s 02:07:03 INFO - Completed test suite (00:19:37) 02:07:03 INFO - javascript benchmark 02:07:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2984.940301193976, 3321.136504508827, 3358.848851801669, 3389.271306669066, 3424.332255210235, 5566.248556444879, 5653.1237658162945, 5718.399568045358, 5744.9425505744985, 5776.624519406241, 5670.914936275959, 5676.460736230062, 5740.483356497914, 5774.566907481934, 5842.123681447774, 5241.842744717665, 5287.47125287472, 5318.946810531884, 5328.387235467912, 5343.65266257692, 5244.056069907415, 5270.0513907496825, 5278.9208161877605, 5325.920111198316, 5328.653637513549, 5158.716270605115, 5274.129768588178, 5328.573714102882, 5388.488093631118, 5403.432639572848, 4289.892752681177, 5251.0, 5273.367195936474, 5309.946900531023, 5315.867103322448, 4943.950560494385, 4972.279019542146, 4992.375953005874, 4998.400191976986, 5023.8492845214705, 5617.354004289518, 5662.235598194232, 5700.543956483471, 5723.51350135239, 5758.683272420015, 6154.169187159721, 6195.113685070928, 6230.158928544633, 6269.62382257066, 6270.278917924452, 6233.158523599302, 6288.968555157241, 6312.779052733133, 6318.905216421757, 6318.936810631927, 5602.1036634138745, 5714.79998200061, 5739.913901291484, 5755.510781583528, 5818.738156782955, 5271.578273738091, 6272.874542509125, 6287.874242515124, 6329.556931014877, 6334.809955701337, 6176.413240742122, 6187.597806142587, 6193.783217587363, 6205.782797602063, 6212.4408803207025, 4877.658563900563, 6105.8843398869, 6139.723712432862, 6189.473894718999, 6277.183966084471, 6224.751009959641, 6237.781677641261, 6259.4679452246155, 6270.561060725706, 6273.404026617464, 6237.064440333896, 6258.998560230432, 6271.247450305992, 6283.340249273851, 6299.433051025429, 4868.659193856467, 4979.726115063634, 4980.05378977993, 5011.223260394645, 5044.520770526794, 4441.378207051015, 4463.732176069446, 4478.686491945533, 4518.525554816762, 4560.087982403533, 5680.971595141998, 5694.288213973254, 5700.714964251771, 5701.2018317435595, 5707.4577915098, 6154.3845615438095, 6312.368763123651, 6326.652034138075, 6335.366463353628, 6339.904901426482, 4704.811807527729, 5549.223108764776, 5640.802571909963, 5667.744951477193, 5732.197492351074, 5408.810691625774, 5438.646487978269, 5444.210589464506, 5447.1829225616475, 5488.094464413327, 6306.4639505642535, 6325.082862984749, 6325.588836725598, 6331.0819931110655, 6335.049742538564, 6192.380761923771, 6216.316205217332, 6230.0, 6272.80816644836, 6301.8109456716375, 6312.873742525032, 6338.58799178052, 6345.619262844244, 6347.523935704933, 6354.650494222862, 6212.378762123752, 6227.532935029982, 6233.158523599437, 6233.781817636361, 6238.189035425547, 6123.70969670409, 6162.07568864656, 6166.290876549254, 6231.781887633911, 6279.748810047547, 4557.703749256288, 6045.75816967334, 6112.442689633789, 6147.047207682816, 6152.0156774915795, 6121.0, 6121.969390153021, 6129.35641757609, 6134.110553969738, 6146.200993870768, 5739.971300143473, 5866.941330586639, 5958.314793798769, 5965.880682386415, 5974.820755377345, 6059.939400606114, 6094.78668246627, 6116.082587611894, 6169.691515424301, 6176.382361763787, 5913.497352725067, 5939.732712028055, 5965.254343207099, 5973.223480947449, 5975.492083172805], "value": 5684.3767150165895, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.71744105859653, 507.2543361258949, 529.1744877990335, 538.7360193505181, 546.2543627947853, 30227.942022029234, 31071.11611048619, 32136.35727285453, 32165.19587010329, 39512.0, 40868.97827554305, 42771.0, 42870.0, 42877.35683964735, 42896.0, 9573.952130239339, 9790.216782657406, 9793.020697930186, 9798.804023919518, 9810.70567882961, 8719.912800872005, 8862.291016718647, 8896.599653015597, 9518.857217141749, 9651.876587150851, 8883.156100170474, 8958.148975847284, 11749.823752643675, 12021.51913923442, 12102.939485302606, 12105.334206618632, 12149.02807775376, 12181.817272740916, 12184.20802647825, 12185.634430967086, 11578.536858525733, 11776.941115294454, 11863.0, 11870.109741769402, 11880.465379057963, 27743.722562774317, 32982.175445613815, 33021.0, 33098.50352244718, 33138.6686133138, 3266.8203248821305, 3393.8472768725464, 3475.5308033415417, 3863.806809659534, 4064.715469917107, 30066.0, 30387.97400215331, 30572.54141187884, 31296.687033129605, 31490.685093149004, 3193.4890417533084, 3205.6794320567988, 3213.678632136791, 3228.6448490666007, 3291.4733642617307, 411341.0, 414936.0, 418206.0, 419197.0, 419621.90189049166, 53965.73017134968, 54358.64103397447, 58893.70553147292, 59173.112403313986, 59193.70403147957, 53838.19242711362, 54991.725041374535, 60823.69588152031, 62032.0, 62369.0, 15528.99061560995, 16522.339106435847, 16525.421610243582, 16536.338546458246, 16542.338306467846, 12786.61640150797, 12872.613821585368, 12880.484780608858, 12893.161944473579, 12895.742085158245, 15105.773413398807, 17156.485305440856, 17179.570510737332, 17204.31182752676, 17221.0, 5608.663480191201, 5741.655500669973, 6699.531032827753, 6789.898151527731, 6839.350261725128, 872.8777971084053, 941.9340646154703, 998.8701468809008, 1032.3031953431405, 1074.4359211413914, 28334.574981375295, 29577.85211073974, 29631.703682962896, 29687.554686679716, 30134.54798178029, 990.0347161517462, 1017.4505766885947, 1060.6923992042375, 1064.2816099133054, 1077.1974878715407, 14093.929530352283, 15818.887089356851, 15946.760798588031, 16224.107674077803, 16243.675126497641, 517496.412517935, 519078.0, 519491.0, 520422.0, 522877.0, 48917.51082489201, 48997.51002490001, 49014.0, 55283.170752438746, 55506.88986220334, 1233.3463264469844, 1258.7419578986285, 1262.0849883834146, 1329.2622594459974, 1341.5103487227286, 29798.851005744833, 30164.69835301619, 30387.848060759556, 30816.229594259425, 30952.53571196479, 48245.41668541483, 48948.51051489582, 48983.26525102055, 49039.75480122577, 49086.75456622694, 428498.0, 429171.0, 432947.8352608217, 434305.0, 435367.0, 24051.0, 24518.75481245236, 24599.0, 24602.876985614956, 25369.492610147325, 45745.0, 46233.306500403196, 46521.53478465307, 47199.29201062056, 47326.05347893092, 37653.81173094227, 37811.865644030724, 38019.86312968828, 38276.0, 39060.80469597634, 37662.81168594139, 38029.809850950565, 38736.03159920912, 39347.409788853765, 39823.00442488845, 33451.16372090717, 36909.07727306839, 37824.05439863916, 37833.0, 37878.2424351517, 23473.413164670335, 31999.040028799172, 33674.0, 33832.60176177081, 33905.66094339025, 24708.8881000359, 24727.50544989126, 24806.0, 25195.622065669395, 25476.872615636803], "value": 19286.25374965131, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.7743955148927, 368.0981595092025, 373.2702566482527, 378.4663624289752, 399.7801209334865, 14949.701005979874, 15066.924665376671, 16198.271077801497, 20459.386218413434, 20936.37190884276, 19231.230750769988, 19739.802601973977, 20157.39527814164, 20389.0, 20622.968851557438, 4939.987302602975, 5899.557533185018, 6049.969750151243, 6096.786612468564, 6101.633901965896, 5985.311689155737, 6034.577579569432, 6089.665068421235, 6091.664958427285, 6107.992181290082, 5741.540676745871, 5983.371745966676, 6031.849203769897, 6033.939660603403, 6038.758449662008, 5961.0, 5985.940140598582, 6011.489023433035, 6015.849603759897, 6032.909506357386, 4958.826441074581, 5162.22566615007, 5198.8440346789275, 5211.140161873324, 5218.5825133989565, 25161.748382516307, 25344.438001159782, 25461.49077018468, 25505.362365940815, 25626.743732562623, 2190.7809219078126, 2208.0, 2271.2845453682203, 2390.34265576966, 2408.6146216605434, 14527.98304118712, 15055.0, 15330.540083797503, 15560.61098472536, 16157.676846463122, 1804.0, 1854.2212270846278, 1902.5624106455455, 1907.6661584222845, 1927.6915693488975, 81592.0, 103752.0, 105054.0, 105878.0, 105944.0, 10338.793224135476, 10522.947385263025, 10872.18458615607, 10960.616378426876, 11290.435478226056, 13563.796543051862, 13605.183688978694, 13607.183568985893, 13626.93186534061, 13639.727205455836, 7439.8140046499275, 8238.588070596446, 8513.233808957224, 8514.106018868053, 8781.07798681142, 8286.46138001028, 8569.400141990125, 8646.6973655923, 8817.0, 8882.86675699865, 5948.643081415043, 6114.991026480581, 6522.640839967421, 6602.60384376939, 6636.203655561267, 1558.7428074367829, 1800.9639807203782, 1826.2514915052761, 1840.7791065072277, 1842.5218081680387, 695.8260434891278, 927.4759760735298, 944.3342443577399, 962.8363178259713, 1067.6530127708527, 16358.672826543403, 19180.61638767217, 22023.889880550494, 22128.668069978965, 22135.44661383478, 760.7071277558069, 931.3945935142076, 996.7341476325026, 1025.564135242519, 1034.7930413917247, 8899.822003559893, 11470.311781293149, 11593.420328983686, 11609.825852612217, 11750.823737643943, 449650.0, 461827.0, 462268.0, 466570.0, 467222.63886805525, 98869.0, 100084.0, 101688.98258314304, 101814.0, 103479.48260258802, 962.5668449197889, 997.516184700106, 1003.2375394699934, 1022.6114076650974, 1024.4826362686795, 14017.299135043411, 19277.0, 19420.4173874784, 19476.0, 19506.512337191118, 46870.0, 47664.570062898165, 48157.0, 48779.51220487892, 48823.0235395283, 63775.04337435035, 64895.0, 65529.017064743115, 65648.34351656423, 66927.66536167289, 6504.38208370204, 6774.86450270982, 7008.894866577108, 7852.450328476952, 8346.916530834857, 12402.027666291715, 12414.503419863104, 13179.0, 13531.458741650224, 13531.526396576073, 11041.83437248458, 11153.721156970816, 11594.072474202212, 11630.709232268922, 11647.126465514952, 11140.610078647209, 11331.0, 11401.660676663774, 11639.0, 11751.706207344885, 9277.536123193679, 11160.888391115985, 11644.825327620261, 11720.41397930083, 11988.76022479563, 7191.7842464726145, 7347.044884165024, 7390.852182956418, 7390.96304518474, 7397.112346518452, 8320.12638672955, 9963.601455941971, 11325.546978120783, 11379.260348077349, 11564.132690048113], "value": 9374.293356967863, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1480.6594483268848, 1521.4598817419815, 1651.9008859468424, 1717.5448506145865, 1847.8059803720616, 7287.672054757535, 8869.02440731519, 8885.200331970129, 8887.866681999767, 8889.777755556115, 6496.480281577463, 8009.519428834259, 8010.239027292412, 8119.228673276044, 8711.869321960176, 2146.6350720377495, 2185.2024011235862, 2324.651302304651, 2402.9759702403016, 2403.387136280246, 2388.414838364603, 2408.4460574067975, 2410.795082417988, 2413.083028449196, 2415.830891837572, 2373.22870067228, 2423.212455951814, 2426.3206302235403, 2432.1730611592056, 2432.6107822748363, 2384.344305316043, 2388.892499837503, 2397.4725560376687, 2403.6755038069987, 2413.227767114524, 2363.5981883079917, 2372.6441033845063, 2384.3800611841048, 2389.8446600971056, 2390.3546042568582, 8760.167784060504, 8956.462612243286, 8956.955215223883, 8973.775655608662, 8980.281577473786, 5264.894702105975, 5422.240886275925, 5434.64674796137, 5442.292501974757, 5443.891122177534, 3511.5848649504546, 3624.981875090634, 3724.255148970217, 3734.8879533614036, 3754.080250338657, 1043.9060484556426, 1094.480121942079, 1102.9503672334683, 1113.4877956140185, 1114.2033446086007, 11692.356920369377, 11724.296542207494, 11734.0, 11739.823902641467, 11760.941195294054, 4728.929066064012, 4730.62155027597, 4834.226523756252, 4941.530554597308, 4973.303737476756, 9132.0, 9198.172164505228, 9199.586018629177, 9202.125798049176, 9207.63169473227, 5268.4994925482015, 5270.947290527122, 5271.42014378418, 5274.393444753902, 5276.92084618731, 4777.402824646919, 4886.2426323919135, 4951.158303088484, 4952.727599982036, 4960.330355402047, 3086.0, 3545.7872527648424, 3646.7447278690765, 3658.231771328051, 3862.343401621731, 1166.2943918929095, 1167.217963964146, 1168.842845582884, 1175.8295047218105, 1177.8939895409283, 842.6671464771381, 861.1603686405705, 885.5926273913943, 893.3791015244343, 956.5552018311519, 5659.518940890071, 5670.603057785916, 5941.792037278675, 6075.939240607626, 6079.118527813443, 907.0611916666219, 936.5270538378076, 944.900785417535, 948.7343543807607, 986.4130842149053, 3924.980375098163, 3947.0921688011917, 4014.3577027675433, 4106.897327566835, 4109.404136400265, 9117.0, 10627.574897004188, 10848.349099054081, 10960.616378426717, 10977.83533247002, 10724.195685323632, 10908.23642345044, 10909.963553462409, 10931.45342732876, 10946.233763636468, 8139.267465928095, 8268.297194738514, 8274.917250827535, 8286.0, 8301.916980830236, 5811.912821307768, 5881.411858814085, 5921.467067963732, 6002.549808764273, 6258.43674069336, 7924.9207507926485, 9915.950420247853, 10187.694369168938, 10191.439470829175, 10246.231532634933, 10079.89920100819, 10204.234682398997, 10245.538950747085, 10260.58957641686, 10267.94866025695, 7428.702851885864, 7504.512206706765, 7597.582132982739, 7618.961905190438, 7623.313901748869, 8218.876716849372, 8244.093149753402, 8396.748097557083, 8401.201885820838, 8404.915950840657, 7469.253074692488, 7547.547147171187, 7558.470907036454, 7584.54492730438, 7744.806379840549, 7373.671871102865, 7387.335139837441, 7389.519681220703, 7392.55644661322, 7433.8884916727375, 6870.587764734132, 6902.482313826383, 6956.582605043714, 6965.6168910708375, 6969.16370035599, 5001.42483614389, 5028.472010438852, 5082.034413461431, 5101.387833460009, 5162.148245539444, 4938.851834444972, 4953.801847926186, 5097.7706003229205, 5226.843194704165, 5247.868803279948], "value": 4585.506491780327, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [263.7823795368821, 300.87664057736333, 306.48357517582866, 311.1396987330032, 312.1042607715856, 5391.757370918308, 5400.675959442437, 5406.864828379293, 5419.051665958456, 5464.9180262296095, 4307.741535507872, 5170.121079416499, 5304.20436934459, 5428.83713488596, 5455.18172274158, 3303.867845286192, 3436.4673475611194, 3898.4601082572367, 3983.4423180754716, 4041.8383264669524, 3653.2876089162623, 3728.117300763111, 3985.760854348734, 3998.480197574311, 4047.392891066334, 4001.639852413297, 4008.6793056555407, 4032.5967403259588, 4044.9191016179657, 4054.858079967202, 3173.1591128351006, 3963.6234557717116, 4001.159756451149, 4028.295048366524, 4053.3717273822604, 2911.708829117071, 3117.485614873543, 3351.8659253629826, 3468.7047305060296, 3508.9824550877083, 5127.307813445174, 5164.476479438575, 5176.618755372533, 5183.870403239912, 5191.974040129813, 3775.4714339992424, 4301.558528461499, 4482.372467854503, 4616.792244349012, 4658.790354434059, 4911.680740751875, 4931.284963680247, 5053.545180933696, 5135.6918584885025, 5426.650818542315, 3087.8764849405998, 3580.4629305604108, 3879.5451705610394, 3894.4547763313158, 3895.8636447724193, 4861.902761944777, 5589.105743081088, 5902.055671092605, 5910.793122240744, 5917.467427931507, 3558.8646811913163, 3833.750806197588, 3846.1538461538416, 3846.538415390171, 3858.5562660294427, 3892.3772196449, 3915.275674000319, 3919.568847426781, 3922.882313530599, 3922.9607703922597, 4031.879043628696, 4239.51245606753, 4473.642108631302, 4493.573110554492, 4526.932096018562, 4404.757738324423, 5089.236614507853, 5366.33761595311, 5574.581906356958, 5764.942350576441, 994.557421947241, 1126.9436528173721, 1313.6321829887647, 1323.4970711129708, 1323.9867601324056, 750.8586947839253, 753.3709352690565, 800.371708209053, 807.289585165051, 813.8128230507017, 4978.751062446863, 5147.382314122255, 5157.638965272395, 5184.481551844785, 5188.403333616682, 5684.317881854204, 5712.143553345097, 5745.2818397700285, 5852.414758524199, 5879.441453061952, 4785.425748910153, 4925.694690906913, 5124.1032819257225, 5173.689578625219, 5360.84741780519, 2884.336602581418, 3059.8372618404865, 3078.2304423894025, 3108.7357574606413, 3124.125524685216, 5889.793857215062, 5922.881542369215, 5979.282486101695, 6003.189569408161, 6003.729832157564, 5225.413420356947, 5988.341282459013, 6013.248343957005, 6026.969865150646, 6109.541784366191, 5156.942826720513, 5618.061768689796, 5947.256592925971, 6100.553187449077, 6117.776444710987, 5560.860978475571, 5796.362400135898, 5855.7364918579615, 5907.084401917709, 5973.640412037134, 4860.76050607107, 5031.396232452129, 5774.307083150049, 5833.241678581757, 5845.901672113787, 5257.710825904612, 5417.377001644703, 5875.824835032925, 6103.84740381494, 6218.347073557211, 3249.3014001989686, 3487.3722729907845, 3786.6213378661914, 3988.641022308006, 4011.3581826907553, 5769.480746732815, 5777.942220577908, 5815.33123690764, 5829.329627092939, 5859.425750292233, 5270.84041510867, 5712.6001179917, 5715.828525144252, 5739.311282646109, 5742.770289188553, 4480.14877173336, 5444.945550544602, 5596.0, 5671.149327600893, 5761.740721667619, 5013.696438925978, 5058.1401161803315, 5508.063629183129, 5556.638818476786, 5614.9719251403485, 4251.407560611278, 4495.698406946102, 4616.953830461653, 5681.744321505625, 5734.799282025109, 4314.525402205818, 5535.7785688572, 5536.0, 5555.2500412445415, 5616.241807356118], "value": 4050.1623656268466, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.7390391651173, 703.1843062048024, 721.9494635375522, 731.1737736357918, 769.6267310354481, 14998.850011499882, 16157.838421615781, 16196.271167797448, 16208.75686864698, 16222.621077208452, 14585.270736463188, 14789.630259243524, 14794.408223671067, 14809.925950370232, 14814.259287035633, 687.5290426058141, 708.7483943200141, 712.2948281201599, 853.9615717292721, 878.7846977490524, 854.3080105114846, 864.511550973701, 873.4453621950043, 873.6592728835727, 875.1773333066928, 914.3462424366577, 914.5381582300929, 914.6935776514875, 918.7565295196778, 919.7194855569048, 721.3039416962604, 907.5689047702355, 908.0374802709116, 911.589784596934, 912.717057712108, 548.8326060551531, 550.8843142939987, 551.5973339462175, 552.5331095224528, 667.7329068372641, 14571.562853114423, 14678.779818302733, 14692.485762998243, 14700.632484187874, 14708.632284192874, 13508.594742157751, 13834.515791947335, 13982.72034559303, 13990.86009139916, 14064.859351406458, 10459.0, 12340.96755168091, 12403.565875194325, 12508.937455312665, 12838.101332906701, 4050.675945924319, 4133.772642504661, 4206.221848957953, 4222.366645003243, 4294.484661840568, 107582.46208768984, 107699.0, 108668.0, 108943.37396382257, 108983.45508272487, 10451.216158788122, 10578.0, 10978.451077446096, 11006.229563930448, 11318.26431281964, 21559.56880862374, 21784.891075544838, 21972.670409943865, 22013.339599812032, 22059.448513786963, 7319.853602927911, 7601.296114628866, 7624.390048796083, 7787.649555770004, 7825.608719563999, 4268.365399904635, 4383.408239887638, 4386.758728269976, 4412.426384569985, 4515.819367225275, 3355.3624811285777, 3513.9648603514147, 3514.103903504641, 3516.367053930266, 3524.8766293179615, 525.905337039329, 643.5945354426775, 647.6696884588894, 656.8883289840738, 665.0356982375478, 540.926974858397, 595.1043679262682, 611.535233222745, 623.797265888579, 628.5788521690479, 7997.400194985399, 8388.203120703523, 8705.173008564303, 9161.024258225727, 9197.310201734897, 576.5113645915197, 589.3399392680226, 612.5085417009595, 619.1363048547341, 629.8488362792988, 4317.7193482423545, 4354.259775838115, 4393.1872603568445, 4395.362672412482, 4456.614106316731, 132441.04015319335, 135258.32370838083, 141953.0, 146601.0, 147244.0, 110706.0, 121707.0, 122971.0, 125514.0, 128894.0, 70522.64738676274, 72849.0, 89052.0, 91424.0, 95060.99999999862, 9505.619775208914, 9532.714018579454, 9592.808143836945, 9698.6120555177, 9731.318807683394, 56493.71753141208, 56590.0, 57515.42484575268, 60303.39696602978, 62579.68710156602, 47812.5218747822, 66429.0, 68816.24735010543, 78543.0, 90060.54969725372, 10655.89344106549, 13387.062905596515, 13592.456301748214, 13767.931160344135, 13875.028747987948, 13753.174809511462, 14167.929160354131, 14558.563243102724, 14561.199134047729, 15375.923120384326, 12056.51773929033, 13569.253691047088, 13826.377812998238, 14096.718065638424, 14101.294935253401, 13170.209787412787, 13589.116707413987, 13876.930615346859, 13884.236366999912, 13931.164130152223, 13165.736685266049, 13231.073824832169, 13327.933360333136, 13416.597502074954, 13442.529511467443, 8987.595558200028, 10467.267291289842, 10736.30214036085, 11350.262232954832, 11534.13493987971, 9815.803683926424, 11249.212555121063, 11433.59982400612, 11485.425728713364, 11499.597514086967], "value": 7570.394946370364, "unit": "score"}], "name": "dromaeo_css", "value": 7244.0604196322, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 118.0, 162.0, 168.0, 114.0, 113.0, 112.0, 115.0, 125.0], "value": 125.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 109.0, 129.0, 158.0, 103.0, 128.0, 127.0, 106.0, 161.0, 107.0], "value": 128.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 187.0, 185.0, 186.0, 184.0, 183.0, 184.0, 184.0, 186.0, 182.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 76.0, 153.0, 57.0, 58.0, 57.0, 66.0, 136.0, 58.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 139.0, 70.0, 75.0, 82.0, 115.0, 68.0, 70.0, 114.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 91.0, 130.0, 125.0, 85.0, 90.0, 86.0, 89.0, 85.0], "value": 95.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 95.0, 96.0, 100.0, 148.0, 162.0, 111.0, 97.0], "value": 109.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 85.0, 83.0, 83.0, 83.0, 85.0, 95.0, 101.0], "value": 86.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 57.0, 56.0, 54.0, 55.0, 54.0, 54.0, 54.0, 54.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 72.0, 99.0, 109.0, 113.0, 72.0, 71.0, 70.0, 71.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 86.0, 84.0, 83.0, 86.0, 85.0, 88.0, 87.0, 100.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 153.0, 155.0, 150.0, 139.0, 140.0, 158.0, 148.0, 185.0, 141.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 169.0, 168.0, 169.0, 171.0, 172.0, 171.0, 168.0, 169.0], "value": 169.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 67.0, 67.0, 67.0, 67.0, 68.0, 67.0, 66.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1510.6, "alertThreshold": 2.0}]} 02:07:03 INFO - Return code: 0 02:07:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:07:03 INFO - # TBPL SUCCESS # 02:07:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:07:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:07:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:07:03 INFO - 2016-09-10 02:07:03.985 screenresolution[1688:14671] starting screenresolution argv=screenresolution get 02:07:03 INFO - 2016-09-10 02:07:03.988 screenresolution[1688:14671] Display 0: 1600x1200x32@60 02:07:03 INFO - 2016-09-10 02:07:03.994 screenresolution[1689:14674] starting screenresolution argv=screenresolution list 02:07:03 INFO - Available Modes on Display 0 02:07:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:07:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:07:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:07:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:07:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:07:04 INFO - Intel Iris: 02:07:04 INFO - Chipset Model: Intel Iris 02:07:04 INFO - Type: GPU 02:07:04 INFO - Bus: Built-In 02:07:04 INFO - VRAM (Dynamic, Max): 1536 MB 02:07:04 INFO - Vendor: Intel (0x8086) 02:07:04 INFO - Device ID: 0x0a2e 02:07:04 INFO - Revision ID: 0x0009 02:07:04 INFO - Displays: 02:07:04 INFO - Display: 02:07:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:07:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:07:04 INFO - Display Serial Number: 1600x1200 60 02:07:04 INFO - Main Display: Yes 02:07:04 INFO - Mirror: Off 02:07:04 INFO - Online: Yes 02:07:04 INFO - Rotation: Supported 02:07:04 INFO - Return code: 0 02:07:04 INFO - Running post-action listener: _resource_record_post_action 02:07:04 INFO - [mozharness: 2016-09-10 09:07:04.232141Z] Finished run-tests step (success) 02:07:04 INFO - Running post-run listener: _resource_record_post_run 02:07:04 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 32594944; Write bytes: 705479680; Read time: 314; Write time: 1825 02:07:04 INFO - TinderboxPrint: CPU usage
53.4% 02:07:04 INFO - TinderboxPrint: I/O read bytes / time
32,594,944 / 314 02:07:04 INFO - TinderboxPrint: I/O write bytes / time
705,479,680 / 1,825 02:07:04 INFO - TinderboxPrint: CPU idle
2,261.4 (46.6%) 02:07:04 INFO - TinderboxPrint: CPU system
965.8 (19.9%) 02:07:04 INFO - TinderboxPrint: CPU user
1,622.6 (33.5%) 02:07:04 INFO - TinderboxPrint: Swap in / out
434,208,768 / 0 02:07:04 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390167040; Write bytes: 383555072; Read time: 28939; Write time: 658 02:07:04 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 31665152; Write bytes: 297782784; Read time: 300; Write time: 1121 02:07:04 INFO - Running post-run listener: _upload_blobber_files 02:07:04 INFO - Blob upload gear active. 02:07:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:07:04 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 02:07:04 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:07:04 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:07:05 INFO - (blobuploader) - INFO - Open directory for files ... 02:07:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:07:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:07:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:07:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:07:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:07:06 INFO - (blobuploader) - INFO - Done attempting. 02:07:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:07:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:07:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:07:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:07:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:07:07 INFO - (blobuploader) - INFO - Done attempting. 02:07:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:07:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:07:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:07:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:07:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:07:09 INFO - (blobuploader) - INFO - Done attempting. 02:07:09 INFO - (blobuploader) - INFO - Iteration through files over. 02:07:09 INFO - Return code: 0 02:07:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:07:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:07:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c75bf52c74716451270140a59538b69d6a222ddca2cdb43899a5494715d6b12aceb62849b2ddce2b77f832b3b4cc0721ca335eb2b04601a0ffd5584567ffe614", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3140787d92f3a245e828d499c6a5688dcb0d4c9e18526c7716ad4bc7cd51de97a489e5b7f1e7836d01a4700d053bb2517415e5d116011aae627d813616de46e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b176cae3bb008423cafc4d6eea6f3b5e4ee0ab847e90b468d3b7b36543046980a2dd04faa8cad76f9d699e624c9ea120e9301c4dbbedcfaad204b93ffe9a8ac"} 02:07:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:07:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:07:09 INFO - Contents: 02:07:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c75bf52c74716451270140a59538b69d6a222ddca2cdb43899a5494715d6b12aceb62849b2ddce2b77f832b3b4cc0721ca335eb2b04601a0ffd5584567ffe614", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3140787d92f3a245e828d499c6a5688dcb0d4c9e18526c7716ad4bc7cd51de97a489e5b7f1e7836d01a4700d053bb2517415e5d116011aae627d813616de46e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b176cae3bb008423cafc4d6eea6f3b5e4ee0ab847e90b468d3b7b36543046980a2dd04faa8cad76f9d699e624c9ea120e9301c4dbbedcfaad204b93ffe9a8ac"} 02:07:09 INFO - Running post-run listener: copy_logs_to_upload_dir 02:07:09 INFO - Copying logs to upload dir... 02:07:09 INFO - mkdir: /builds/slave/test/build/upload/logs 02:07:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.663478 ========= master_lag: 1.63 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-10 02:07:11.573320) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 02:07:11.577092) ========= 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.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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/Firefox/sha512/c75bf52c74716451270140a59538b69d6a222ddca2cdb43899a5494715d6b12aceb62849b2ddce2b77f832b3b4cc0721ca335eb2b04601a0ffd5584567ffe614", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3140787d92f3a245e828d499c6a5688dcb0d4c9e18526c7716ad4bc7cd51de97a489e5b7f1e7836d01a4700d053bb2517415e5d116011aae627d813616de46e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b176cae3bb008423cafc4d6eea6f3b5e4ee0ab847e90b468d3b7b36543046980a2dd04faa8cad76f9d699e624c9ea120e9301c4dbbedcfaad204b93ffe9a8ac"} build_url:https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/RtZKEDYDQNCBBZbcOm867w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c75bf52c74716451270140a59538b69d6a222ddca2cdb43899a5494715d6b12aceb62849b2ddce2b77f832b3b4cc0721ca335eb2b04601a0ffd5584567ffe614", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3140787d92f3a245e828d499c6a5688dcb0d4c9e18526c7716ad4bc7cd51de97a489e5b7f1e7836d01a4700d053bb2517415e5d116011aae627d813616de46e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b176cae3bb008423cafc4d6eea6f3b5e4ee0ab847e90b468d3b7b36543046980a2dd04faa8cad76f9d699e624c9ea120e9301c4dbbedcfaad204b93ffe9a8ac"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 02:07:11.615496) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 02:07:11.615864) ========= 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.eAVzvSxZGv/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.OhKj6ZTlSX/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.005230 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 02:07:11.666866) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 02:07:11.667461) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 02:07:11.668226) ========= ========= Total master_lag: 1.78 =========