builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0181 starttime: 1473018686.88 results: success (0) buildid: 20160904103751 builduid: 5eba552361004b95be71188a107cc1b8 revision: dbe4b47941c7b3d6298a0ead5e40dd828096c808 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-04 12:51:26.880201) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-04 12:51:26.880616) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-04 12:51:26.880892) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-04 12:51:26.911730) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:51:26.912120) ========= 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.zhqEt3l95f/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.XQFAozgsU1/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-04 12:51:26-- 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% 15.5M=0.001s 2016-09-04 12:51:27 (15.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122177 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:51:27.057937) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:51:27.058277) ========= 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.zhqEt3l95f/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.XQFAozgsU1/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.028367 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:51:27.107422) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-04 12:51:27.109828) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dbe4b47941c7b3d6298a0ead5e40dd828096c808 --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 dbe4b47941c7b3d6298a0ead5e40dd828096c808 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zhqEt3l95f/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.XQFAozgsU1/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-04 12:51:27,187 truncating revision to first 12 chars 2016-09-04 12:51:27,187 Setting DEBUG logging. 2016-09-04 12:51:27,188 attempt 1/10 2016-09-04 12:51:27,188 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dbe4b47941c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-04 12:51:28,248 unpacking tar archive at: mozilla-central-dbe4b47941c7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.341351 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-04 12:51:28.474489) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-04 12:51:28.475745) ========= script_repo_revision: dbe4b47941c7b3d6298a0ead5e40dd828096c808 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-04 12:51:28.476134) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-04 12:51:28.476400) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-04 12:51:28.494902) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-04 12:51:28.495203) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.zhqEt3l95f/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.XQFAozgsU1/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 12:51:28 INFO - MultiFileLogger online at 20160904 12:51:28 in /builds/slave/test 12:51:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:51:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:51:28 INFO - {'append_to_log': False, 12:51:28 INFO - 'base_work_dir': '/builds/slave/test', 12:51:28 INFO - 'blob_upload_branch': 'Firefox', 12:51:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:51:28 INFO - 'branch': 'Firefox', 12:51:28 INFO - 'buildbot_json_path': 'buildprops.json', 12:51:28 INFO - 'config_files': ('talos/mac_config.py',), 12:51:28 INFO - 'default_actions': ('clobber', 12:51:28 INFO - 'read-buildbot-config', 12:51:28 INFO - 'download-and-extract', 12:51:28 INFO - 'populate-webroot', 12:51:28 INFO - 'create-virtualenv', 12:51:28 INFO - 'install', 12:51:28 INFO - 'run-tests'), 12:51:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:51:28 INFO - 'download_minidump_stackwalk': True, 12:51:28 INFO - 'download_symbols': 'ondemand', 12:51:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:51:28 INFO - 'tooltool.py': '/tools/tooltool.py', 12:51:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:51:28 INFO - '/tools/misc-python/virtualenv.py')}, 12:51:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:51:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:51:28 INFO - 'installer_path': 'installer.exe', 12:51:28 INFO - 'log_level': 'info', 12:51:28 INFO - 'log_name': 'talos', 12:51:28 INFO - 'log_to_console': True, 12:51:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:51:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:51:28 INFO - 'opt_config_files': (), 12:51:28 INFO - 'pip_index': False, 12:51:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:51:28 INFO - 'cmd': ('bash', 12:51:28 INFO - '-c', 12:51:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:51:28 INFO - 'enabled': True, 12:51:28 INFO - 'halt_on_failure': False, 12:51:28 INFO - 'name': 'check_screen_resolution'},), 12:51:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:51:28 INFO - 'cmd': ('bash', 12:51:28 INFO - '-c', 12:51:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:51:28 INFO - 'enabled': True, 12:51:28 INFO - 'halt_on_failure': False, 12:51:28 INFO - 'name': 'check_screen_resolution'},), 12:51:28 INFO - 'run_cmd_checks_enabled': True, 12:51:28 INFO - 'sps_profile': False, 12:51:28 INFO - 'sps_profile_interval': 0, 12:51:28 INFO - 'suite': 'dromaeojs-e10s', 12:51:28 INFO - 'system_bits': '32', 12:51:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:51:28 INFO - 'title': 't-yosemite-r7-0181', 12:51:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:51:28 INFO - 'use_talos_json': True, 12:51:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:51:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:51:28 INFO - 'work_dir': 'build'} 12:51:28 INFO - [mozharness: 2016-09-04 19:51:28.694098Z] Running clobber step. 12:51:28 INFO - Running pre-action listener: _resource_record_pre_action 12:51:28 INFO - Running main action method: clobber 12:51:28 INFO - rmtree: /builds/slave/test/build 12:51:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:51:30 INFO - Running post-action listener: _resource_record_post_action 12:51:30 INFO - [mozharness: 2016-09-04 19:51:30.741931Z] Finished clobber step (success) 12:51:30 INFO - [mozharness: 2016-09-04 19:51:30.742064Z] Running read-buildbot-config step. 12:51:30 INFO - Running pre-action listener: _resource_record_pre_action 12:51:30 INFO - Running main action method: read_buildbot_config 12:51:30 INFO - Using buildbot properties: 12:51:30 INFO - { 12:51:30 INFO - "project": "", 12:51:30 INFO - "product": "firefox", 12:51:30 INFO - "script_repo_revision": "production", 12:51:30 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:51:30 INFO - "repository": "", 12:51:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:51:30 INFO - "buildid": "20160904103751", 12:51:30 INFO - "pgo_build": "False", 12:51:30 INFO - "basedir": "/builds/slave/test", 12:51:30 INFO - "buildnumber": 47, 12:51:30 INFO - "slavename": "t-yosemite-r7-0181", 12:51:30 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 12:51:30 INFO - "platform": "yosemite_r7", 12:51:30 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:51:30 INFO - "branch": "mozilla-central", 12:51:30 INFO - "repo_path": "mozilla-central", 12:51:30 INFO - "revision": "dbe4b47941c7b3d6298a0ead5e40dd828096c808", 12:51:30 INFO - "stage_platform": "macosx64", 12:51:30 INFO - "builduid": "5eba552361004b95be71188a107cc1b8", 12:51:30 INFO - "slavebuilddir": "test" 12:51:30 INFO - } 12:51:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 12:51:30 INFO - Running post-action listener: _resource_record_post_action 12:51:30 INFO - [mozharness: 2016-09-04 19:51:30.745329Z] Finished read-buildbot-config step (success) 12:51:30 INFO - [mozharness: 2016-09-04 19:51:30.745471Z] Running download-and-extract step. 12:51:30 INFO - Running pre-action listener: _resource_record_pre_action 12:51:30 INFO - Running main action method: download_and_extract 12:51:30 INFO - mkdir: /builds/slave/test/build/tests 12:51:30 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')]} 12:51:30 INFO - trying https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 12:51:30 INFO - Downloading https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/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 12:51:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/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 12:51:32 INFO - Downloaded 1199 bytes. 12:51:32 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 12:51:32 INFO - Using the following test package requirements: 12:51:32 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:51:32 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:51:32 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 12:51:32 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:51:32 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 12:51:32 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:51:32 INFO - u'jsshell-mac.zip'], 12:51:32 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:51:32 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 12:51:32 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:51:32 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:51:32 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 12:51:32 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:51:32 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 12:51:32 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:51:32 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 12:51:32 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:51:32 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 12:51:32 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:51:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:51:32 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 12:51:39 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 12:51:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:51:40 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 12:51:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 12:51:48 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 12:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:51:48 INFO - trying https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:51:48 INFO - Downloading https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 12:51:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/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 12:51:51 INFO - Downloaded 111478948 bytes. 12:51:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:51:51 INFO - mkdir: /builds/slave/test/properties 12:51:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:51:51 INFO - Writing to file /builds/slave/test/properties/build_url 12:51:51 INFO - Contents: 12:51:51 INFO - build_url:https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:51:52 INFO - Running post-action listener: _resource_record_post_action 12:51:52 INFO - Running post-action listener: set_extra_try_arguments 12:51:52 INFO - [mozharness: 2016-09-04 19:51:52.274841Z] Finished download-and-extract step (success) 12:51:52 INFO - [mozharness: 2016-09-04 19:51:52.274972Z] Running populate-webroot step. 12:51:52 INFO - Running pre-action listener: _resource_record_pre_action 12:51:52 INFO - Running main action method: populate_webroot 12:51:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:51:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:51:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:51:52 INFO - '--tppagecycles', 12:51:52 INFO - '7'], 12:51:52 INFO - 'tests': ['tsvgm']}}, 12:51:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:51:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:51:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:51:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:51:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:51:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:51:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:51:52 INFO - 'talos_options': ['--disable-e10s'], 12:51:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:51:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:51:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:51:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:51:52 INFO - 'talos_options': ['--disable-e10s'], 12:51:52 INFO - 'tests': ['damp', 'tps']}, 12:51:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:51:52 INFO - 'tests': ['damp', 'tps']}, 12:51:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:51:52 INFO - 'tests': ['dromaeo_dom']}, 12:51:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:51:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:51:52 INFO - 'tests': ['basic_compositor_video']}, 12:51:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:51:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:51:52 INFO - 'tests': ['a11yr', 12:51:52 INFO - 'ts_paint', 12:51:52 INFO - 'tpaint', 12:51:52 INFO - 'sessionrestore', 12:51:52 INFO - 'sessionrestore_no_auto_restore', 12:51:52 INFO - 'tabpaint']}, 12:51:52 INFO - 'other-e10s': {'tests': ['a11yr', 12:51:52 INFO - 'ts_paint', 12:51:52 INFO - 'tpaint', 12:51:52 INFO - 'sessionrestore', 12:51:52 INFO - 'sessionrestore_no_auto_restore', 12:51:52 INFO - 'tabpaint']}, 12:51:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:51:52 INFO - 'ts_paint', 12:51:52 INFO - 'tpaint', 12:51:52 INFO - 'sessionrestore', 12:51:52 INFO - 'sessionrestore_no_auto_restore', 12:51:52 INFO - 'tabpaint']}, 12:51:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:51:52 INFO - 'ts_paint', 12:51:52 INFO - 'tpaint', 12:51:52 INFO - 'sessionrestore', 12:51:52 INFO - 'sessionrestore_no_auto_restore', 12:51:52 INFO - 'tabpaint']}, 12:51:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:51:52 INFO - 'tests': ['a11yr', 12:51:52 INFO - 'ts_paint', 12:51:52 INFO - 'tpaint', 12:51:52 INFO - 'sessionrestore', 12:51:52 INFO - 'sessionrestore_no_auto_restore', 12:51:52 INFO - 'tabpaint']}, 12:51:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:51:52 INFO - 'tests': ['a11yr', 12:51:52 INFO - 'ts_paint', 12:51:52 INFO - 'tpaint', 12:51:52 INFO - 'sessionrestore', 12:51:52 INFO - 'sessionrestore_no_auto_restore', 12:51:52 INFO - 'tabpaint']}, 12:51:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:51:52 INFO - 'tests': ['tsvgx', 12:51:52 INFO - 'tsvgr_opacity', 12:51:52 INFO - 'tart', 12:51:52 INFO - 'tscrollx', 12:51:52 INFO - 'cart']}, 12:51:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:51:52 INFO - 'tsvgr_opacity', 12:51:52 INFO - 'tart', 12:51:52 INFO - 'tscrollx', 12:51:52 INFO - 'cart']}, 12:51:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:51:52 INFO - 'talos_options': ['--disable-e10s'], 12:51:52 INFO - 'tests': ['tp5o']}, 12:51:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:51:52 INFO - 'tests': ['tp5o']}, 12:51:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:51:52 INFO - 'talos_options': ['--disable-e10s', 12:51:52 INFO - '--xperf_path', 12:51:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:51:52 INFO - 'tests': ['tp5n']}, 12:51:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:51:52 INFO - 'talos_options': ['--xperf_path', 12:51:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:51:52 INFO - 'tests': ['tp5n']}}, 12:51:52 INFO - 'talos.zip': {'path': '', 12:51:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:51:52 INFO - Running post-action listener: _resource_record_post_action 12:51:52 INFO - [mozharness: 2016-09-04 19:51:52.294019Z] Finished populate-webroot step (success) 12:51:52 INFO - [mozharness: 2016-09-04 19:51:52.294160Z] Running create-virtualenv step. 12:51:52 INFO - Running pre-action listener: _resource_record_pre_action 12:51:52 INFO - Running main action method: create_virtualenv 12:51:52 INFO - Creating virtualenv /builds/slave/test/build/venv 12:51:52 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 12:51:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:51:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:51:52 INFO - Using real prefix '/tools/python27' 12:51:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:51:53 INFO - Installing distribute.............................................................................................................................................................................................done. 12:51:55 INFO - Installing pip.................done. 12:51:55 INFO - Return code: 0 12:51:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:51:55 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')]} 12:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:51:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:55 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')]} 12:51:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:51:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:55 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 0x10bef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf8d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aead6d870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be5cd30>, '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 0x10bbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be54b20>, '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.zhqEt3l95f/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.XQFAozgsU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 12:51:55 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 12:51:55 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 12:51:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zhqEt3l95f/Render', 12:51:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:51:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:51:55 INFO - 'HOME': '/Users/cltbld', 12:51:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:51:56 INFO - 'LOGNAME': 'cltbld', 12:51:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:51:56 INFO - 'MOZ_NO_REMOTE': '1', 12:51:56 INFO - 'NO_EM_RESTART': '1', 12:51:56 INFO - 'PAGER': '/bin/cat', 12:51:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:51:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:51:56 INFO - 'PWD': '/builds/slave/test', 12:51:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:51:56 INFO - 'SHELL': '/bin/bash', 12:51:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQFAozgsU1/Listeners', 12:51:56 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 12:51:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:51:56 INFO - 'USER': 'cltbld', 12:51:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:51:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:51:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:51:56 INFO - 'XPC_FLAGS': '0x0', 12:51:56 INFO - 'XPC_SERVICE_NAME': '0', 12:51:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:51:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:51:56 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 12:51:56 INFO - Cleaning up... 12:51:56 INFO - Return code: 0 12:51:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:51:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:51:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:51:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:51:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:56 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 0x10bef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf8d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aead6d870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be5cd30>, '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 0x10bbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be54b20>, '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.zhqEt3l95f/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.XQFAozgsU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 12:51:56 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 12:51:56 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 12:51:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zhqEt3l95f/Render', 12:51:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:51:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:51:56 INFO - 'HOME': '/Users/cltbld', 12:51:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:51:56 INFO - 'LOGNAME': 'cltbld', 12:51:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:51:56 INFO - 'MOZ_NO_REMOTE': '1', 12:51:56 INFO - 'NO_EM_RESTART': '1', 12:51:56 INFO - 'PAGER': '/bin/cat', 12:51:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:51:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:51:56 INFO - 'PWD': '/builds/slave/test', 12:51:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:51:56 INFO - 'SHELL': '/bin/bash', 12:51:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQFAozgsU1/Listeners', 12:51:56 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 12:51:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:51:56 INFO - 'USER': 'cltbld', 12:51:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:51:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:51:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:51:56 INFO - 'XPC_FLAGS': '0x0', 12:51:56 INFO - 'XPC_SERVICE_NAME': '0', 12:51:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:51:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:51:56 INFO - Downloading/unpacking psutil>=3.1.1 12:51:56 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 12:51:56 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 12:51:56 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 12:51:56 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 12:51:56 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 12:51:56 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 12:51:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:51:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:51:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:51:59 INFO - Installing collected packages: psutil 12:51:59 INFO - Running setup.py install for psutil 12:51:59 INFO - building 'psutil._psutil_osx' extension 12:51:59 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 12:52:00 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 12:52:00 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 12:52:00 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 12:52:00 INFO - building 'psutil._psutil_posix' extension 12:52:00 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 12:52:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:52:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:52:00 INFO - ^ 12:52:00 INFO - 1 warning generated. 12:52:00 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 12:52:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:52:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:52:00 INFO - Successfully installed psutil 12:52:00 INFO - Cleaning up... 12:52:00 INFO - Return code: 0 12:52:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:00 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 0x10bef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf8d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aead6d870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be5cd30>, '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 0x10bbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be54b20>, '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.zhqEt3l95f/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.XQFAozgsU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 12:52:00 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 12:52:00 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 12:52:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zhqEt3l95f/Render', 12:52:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:52:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:52:00 INFO - 'HOME': '/Users/cltbld', 12:52:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:52:00 INFO - 'LOGNAME': 'cltbld', 12:52:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:00 INFO - 'MOZ_NO_REMOTE': '1', 12:52:00 INFO - 'NO_EM_RESTART': '1', 12:52:00 INFO - 'PAGER': '/bin/cat', 12:52:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:52:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:00 INFO - 'PWD': '/builds/slave/test', 12:52:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:52:00 INFO - 'SHELL': '/bin/bash', 12:52:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQFAozgsU1/Listeners', 12:52:00 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 12:52:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:52:00 INFO - 'USER': 'cltbld', 12:52:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:52:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:52:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:00 INFO - 'XPC_FLAGS': '0x0', 12:52:00 INFO - 'XPC_SERVICE_NAME': '0', 12:52:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:52:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:52:01 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 12:52:01 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 12:52:01 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 12:52:01 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 12:52:01 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 12:52:01 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 12:52:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:52:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:52:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:52:03 INFO - Installing collected packages: mozsystemmonitor 12:52:03 INFO - Running setup.py install for mozsystemmonitor 12:52:03 INFO - Successfully installed mozsystemmonitor 12:52:03 INFO - Cleaning up... 12:52:03 INFO - Return code: 0 12:52:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf8d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aead6d870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be5cd30>, '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 0x10bbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be54b20>, '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.zhqEt3l95f/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.XQFAozgsU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 12:52:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:52:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:52:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zhqEt3l95f/Render', 12:52:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:52:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:52:03 INFO - 'HOME': '/Users/cltbld', 12:52:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:52:03 INFO - 'LOGNAME': 'cltbld', 12:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:03 INFO - 'MOZ_NO_REMOTE': '1', 12:52:03 INFO - 'NO_EM_RESTART': '1', 12:52:03 INFO - 'PAGER': '/bin/cat', 12:52:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:03 INFO - 'PWD': '/builds/slave/test', 12:52:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:52:03 INFO - 'SHELL': '/bin/bash', 12:52:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQFAozgsU1/Listeners', 12:52:03 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 12:52:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:52:03 INFO - 'USER': 'cltbld', 12:52:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:52:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:03 INFO - 'XPC_FLAGS': '0x0', 12:52:03 INFO - 'XPC_SERVICE_NAME': '0', 12:52:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:52:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:04 INFO - Downloading/unpacking jsonschema==2.5.1 12:52:04 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 12:52:04 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 12:52:04 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 12:52:04 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 12:52:04 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 12:52:04 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 12:52:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:52:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:52:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:52:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:52:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:52:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:52:07 INFO - Downloading functools32-3.2.3-2.tar.gz 12:52:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:52:07 INFO - warning: no files found matching '*.txt' 12:52:07 INFO - no previously-included directories found matching 'build' 12:52:07 INFO - no previously-included directories found matching 'dist' 12:52:07 INFO - no previously-included directories found matching '.git*' 12:52:07 INFO - Installing collected packages: jsonschema, functools32 12:52:07 INFO - Running setup.py install for functools32 12:52:07 INFO - warning: no files found matching '*.txt' 12:52:07 INFO - no previously-included directories found matching 'build' 12:52:07 INFO - no previously-included directories found matching 'dist' 12:52:07 INFO - no previously-included directories found matching '.git*' 12:52:07 INFO - Successfully installed jsonschema functools32 12:52:07 INFO - Cleaning up... 12:52:07 INFO - Return code: 0 12:52:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:52:07 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')]} 12:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:07 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')]} 12:52:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:07 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 0x10bef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf8d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aead6d870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be5cd30>, '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 0x10bbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be54b20>, '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.zhqEt3l95f/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.XQFAozgsU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 12:52:07 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 12:52:07 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 12:52:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zhqEt3l95f/Render', 12:52:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:52:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:52:07 INFO - 'HOME': '/Users/cltbld', 12:52:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:52:07 INFO - 'LOGNAME': 'cltbld', 12:52:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:07 INFO - 'MOZ_NO_REMOTE': '1', 12:52:07 INFO - 'NO_EM_RESTART': '1', 12:52:07 INFO - 'PAGER': '/bin/cat', 12:52:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:52:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:07 INFO - 'PWD': '/builds/slave/test', 12:52:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:52:07 INFO - 'SHELL': '/bin/bash', 12:52:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQFAozgsU1/Listeners', 12:52:07 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 12:52:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:52:07 INFO - 'USER': 'cltbld', 12:52:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:52:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:52:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:07 INFO - 'XPC_FLAGS': '0x0', 12:52:07 INFO - 'XPC_SERVICE_NAME': '0', 12:52:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:52:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:52:07 INFO - Cleaning up... 12:52:07 INFO - Return code: 0 12:52:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:52:07 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')]} 12:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:07 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')]} 12:52:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:07 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 0x10bef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf8d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aead6d870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be5cd30>, '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 0x10bbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be54b20>, '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.zhqEt3l95f/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.XQFAozgsU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 12:52:07 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 12:52:07 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 12:52:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zhqEt3l95f/Render', 12:52:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:52:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:52:07 INFO - 'HOME': '/Users/cltbld', 12:52:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:52:07 INFO - 'LOGNAME': 'cltbld', 12:52:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:07 INFO - 'MOZ_NO_REMOTE': '1', 12:52:07 INFO - 'NO_EM_RESTART': '1', 12:52:07 INFO - 'PAGER': '/bin/cat', 12:52:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:52:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:07 INFO - 'PWD': '/builds/slave/test', 12:52:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:52:07 INFO - 'SHELL': '/bin/bash', 12:52:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQFAozgsU1/Listeners', 12:52:07 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 12:52:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:52:07 INFO - 'USER': 'cltbld', 12:52:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:52:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:52:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:07 INFO - 'XPC_FLAGS': '0x0', 12:52:07 INFO - 'XPC_SERVICE_NAME': '0', 12:52:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:52:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:07 INFO - Downloading/unpacking blobuploader==1.2.4 12:52:07 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 12:52:07 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 12:52:07 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 12:52:07 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 12:52:07 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 12:52:07 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 12:52:10 INFO - Downloading blobuploader-1.2.4.tar.gz 12:52:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:52:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:52:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:52:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:52:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:52:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:52:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:52:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:52:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:52:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:52:11 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 12:52:11 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 12:52:11 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 12:52:11 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 12:52:11 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 12:52:11 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 12:52:11 INFO - Downloading docopt-0.6.1.tar.gz 12:52:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:52:11 INFO - Installing collected packages: blobuploader, requests, docopt 12:52:11 INFO - Running setup.py install for blobuploader 12:52:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:52:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:52:11 INFO - Running setup.py install for requests 12:52:12 INFO - Running setup.py install for docopt 12:52:12 INFO - Successfully installed blobuploader requests docopt 12:52:12 INFO - Cleaning up... 12:52:12 INFO - Return code: 0 12:52:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:12 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 0x10bef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf8d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aead6d870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be5cd30>, '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 0x10bbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be54b20>, '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.zhqEt3l95f/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.XQFAozgsU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 12:52:12 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 12:52:12 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 12:52:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zhqEt3l95f/Render', 12:52:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:52:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:52:12 INFO - 'HOME': '/Users/cltbld', 12:52:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:52:12 INFO - 'LOGNAME': 'cltbld', 12:52:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:12 INFO - 'MOZ_NO_REMOTE': '1', 12:52:12 INFO - 'NO_EM_RESTART': '1', 12:52:12 INFO - 'PAGER': '/bin/cat', 12:52:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:52:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:12 INFO - 'PWD': '/builds/slave/test', 12:52:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:52:12 INFO - 'SHELL': '/bin/bash', 12:52:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQFAozgsU1/Listeners', 12:52:12 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 12:52:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:52:12 INFO - 'USER': 'cltbld', 12:52:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:52:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:52:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:12 INFO - 'XPC_FLAGS': '0x0', 12:52:12 INFO - 'XPC_SERVICE_NAME': '0', 12:52:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:52:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:52:12 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ZJ7hts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:52:12 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-lYaEjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:52:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-vE5iPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:52:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-5Jwh0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:52:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-KDe4Kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:52:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-7LPwnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:52:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-BKUdJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:52:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-l41XB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:52:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-cbeK50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:52:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-bhflQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:52:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-lBpU7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:52:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-rzEXWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:52:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-eqafTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:52:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-9RacXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:52:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-VyRhl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:52:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-JiDnO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:52:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-85UTIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:52:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:52:14 INFO - Running setup.py install for manifestparser 12:52:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:52:14 INFO - Running setup.py install for mozcrash 12:52:14 INFO - Running setup.py install for mozdebug 12:52:15 INFO - Running setup.py install for mozdevice 12:52:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:52:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:52:15 INFO - Running setup.py install for mozfile 12:52:15 INFO - Running setup.py install for mozhttpd 12:52:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:52:15 INFO - Running setup.py install for mozinfo 12:52:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:52:15 INFO - Running setup.py install for mozInstall 12:52:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:52:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:52:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:52:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:52:15 INFO - Running setup.py install for mozleak 12:52:15 INFO - Running setup.py install for mozlog 12:52:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:52:16 INFO - Running setup.py install for moznetwork 12:52:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:52:16 INFO - Running setup.py install for mozprocess 12:52:16 INFO - Running setup.py install for mozprofile 12:52:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:52:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:52:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:52:16 INFO - Running setup.py install for mozrunner 12:52:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:52:16 INFO - Running setup.py install for mozscreenshot 12:52:16 INFO - Running setup.py install for moztest 12:52:17 INFO - Running setup.py install for mozversion 12:52:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:52:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:52:17 INFO - Cleaning up... 12:52:17 INFO - Return code: 0 12:52:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:17 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 0x10bef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf8d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aead6d870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be5cd30>, '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 0x10bbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be54b20>, '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.zhqEt3l95f/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.XQFAozgsU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 12:52:17 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 12:52:17 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 12:52:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zhqEt3l95f/Render', 12:52:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:52:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:52:17 INFO - 'HOME': '/Users/cltbld', 12:52:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:52:17 INFO - 'LOGNAME': 'cltbld', 12:52:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:17 INFO - 'MOZ_NO_REMOTE': '1', 12:52:17 INFO - 'NO_EM_RESTART': '1', 12:52:17 INFO - 'PAGER': '/bin/cat', 12:52:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:52:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:17 INFO - 'PWD': '/builds/slave/test', 12:52:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:52:17 INFO - 'SHELL': '/bin/bash', 12:52:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQFAozgsU1/Listeners', 12:52:17 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 12:52:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:52:17 INFO - 'USER': 'cltbld', 12:52:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:52:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:52:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:17 INFO - 'XPC_FLAGS': '0x0', 12:52:17 INFO - 'XPC_SERVICE_NAME': '0', 12:52:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:52:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:52:17 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-vEp1vH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:52:17 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)) 12:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:52:17 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-DB7mto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:52:17 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)) 12:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:52:17 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-6rhCjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:52:17 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)) 12:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:52:17 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-cDxeDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:52:17 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)) 12:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:52:17 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-gzjXpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:52:18 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)) 12:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:52:18 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-SL10ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:52:18 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)) 12:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:52:18 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-xwiFKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:52:18 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)) 12:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:52:18 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-wI5Oy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:52:18 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)) 12:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:52:18 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-kFtoWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:52:18 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)) 12:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:52:18 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-0v6uEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:52:18 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)) 12:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:52:18 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-4snib7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:52:18 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)) 12:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:52:18 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-NCF1CS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:52:18 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)) 12:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:52:18 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-EYrLOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:52:18 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)) 12:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:52:18 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-2vuRsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:52:18 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)) 12:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:52:18 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-xuwo5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:52:19 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)) 12:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:52:19 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-jEUCF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:52:19 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)) 12:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:52:19 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-5OjwaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:52:19 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)) 12:52:19 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)) 12:52:19 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)) 12:52:19 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)) 12:52:19 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)) 12:52:19 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)) 12:52:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:52:19 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 12:52:19 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 12:52:19 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 12:52:19 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 12:52:19 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 12:52:19 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 12:52:22 INFO - Downloading blessings-1.6.tar.gz 12:52:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:52:22 INFO - Installing collected packages: blessings 12:52:22 INFO - Running setup.py install for blessings 12:52:22 INFO - Successfully installed blessings 12:52:22 INFO - Cleaning up... 12:52:22 INFO - Return code: 0 12:52:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:52:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:52:22 INFO - Reading from file tmpfile_stdout 12:52:22 INFO - Current package versions: 12:52:22 INFO - blessings == 1.6 12:52:22 INFO - blobuploader == 1.2.4 12:52:22 INFO - docopt == 0.6.1 12:52:22 INFO - functools32 == 3.2.3-2 12:52:22 INFO - jsonschema == 2.5.1 12:52:22 INFO - manifestparser == 1.1 12:52:22 INFO - mozInstall == 1.12 12:52:22 INFO - mozcrash == 0.17 12:52:22 INFO - mozdebug == 0.1 12:52:22 INFO - mozdevice == 0.48 12:52:22 INFO - mozfile == 1.2 12:52:22 INFO - mozhttpd == 0.7 12:52:22 INFO - mozinfo == 0.9 12:52:22 INFO - mozleak == 0.1 12:52:22 INFO - mozlog == 3.3 12:52:22 INFO - moznetwork == 0.27 12:52:22 INFO - mozprocess == 0.23 12:52:22 INFO - mozprofile == 0.28 12:52:22 INFO - mozrunner == 6.12 12:52:22 INFO - mozscreenshot == 0.1 12:52:22 INFO - mozsystemmonitor == 0.3 12:52:22 INFO - moztest == 0.7 12:52:22 INFO - mozversion == 1.4 12:52:22 INFO - psutil == 3.1.1 12:52:22 INFO - requests == 1.2.3 12:52:22 INFO - wsgiref == 0.1.2 12:52:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:52:22 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')]} 12:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52: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')]} 12:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:23 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 0x10bef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf8d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aead6d870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be5cd30>, '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 0x10bbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be54b20>, '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.zhqEt3l95f/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.XQFAozgsU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 12:52:23 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 12:52:23 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 12:52:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zhqEt3l95f/Render', 12:52:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:52:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:52:23 INFO - 'HOME': '/Users/cltbld', 12:52:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:52:23 INFO - 'LOGNAME': 'cltbld', 12:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:23 INFO - 'MOZ_NO_REMOTE': '1', 12:52:23 INFO - 'NO_EM_RESTART': '1', 12:52:23 INFO - 'PAGER': '/bin/cat', 12:52:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:23 INFO - 'PWD': '/builds/slave/test', 12:52:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:52:23 INFO - 'SHELL': '/bin/bash', 12:52:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQFAozgsU1/Listeners', 12:52:23 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 12:52:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:52:23 INFO - 'USER': 'cltbld', 12:52:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:52:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:23 INFO - 'XPC_FLAGS': '0x0', 12:52:23 INFO - 'XPC_SERVICE_NAME': '0', 12:52:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:52:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:23 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)) 12:52:23 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)) 12:52:23 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)) 12:52:23 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)) 12:52:23 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)) 12:52:23 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)) 12:52:23 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)) 12:52:23 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)) 12:52:23 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)) 12:52:23 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)) 12:52:23 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)) 12:52:23 INFO - Cleaning up... 12:52:23 INFO - Return code: 0 12:52:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:52: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')]} 12:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52: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')]} 12:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf8d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aead6d870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be5cd30>, '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 0x10bbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be54b20>, '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.zhqEt3l95f/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.XQFAozgsU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 12:52: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', 'jsonschema'] in /builds/slave/test/build 12:52: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 jsonschema 12:52:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zhqEt3l95f/Render', 12:52:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:52:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:52:23 INFO - 'HOME': '/Users/cltbld', 12:52:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:52:23 INFO - 'LOGNAME': 'cltbld', 12:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:23 INFO - 'MOZ_NO_REMOTE': '1', 12:52:23 INFO - 'NO_EM_RESTART': '1', 12:52:23 INFO - 'PAGER': '/bin/cat', 12:52:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:23 INFO - 'PWD': '/builds/slave/test', 12:52:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:52:23 INFO - 'SHELL': '/bin/bash', 12:52:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQFAozgsU1/Listeners', 12:52:23 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 12:52:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:52:23 INFO - 'USER': 'cltbld', 12:52:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:52:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:23 INFO - 'XPC_FLAGS': '0x0', 12:52:23 INFO - 'XPC_SERVICE_NAME': '0', 12:52:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:52:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:52:23 INFO - Cleaning up... 12:52:23 INFO - Return code: 0 12:52:23 INFO - Running post-action listener: _resource_record_post_action 12:52:23 INFO - Running post-action listener: _start_resource_monitoring 12:52:23 INFO - Starting resource monitoring. 12:52:23 INFO - [mozharness: 2016-09-04 19:52:23.825130Z] Finished create-virtualenv step (success) 12:52:23 INFO - [mozharness: 2016-09-04 19:52:23.826132Z] Running install step. 12:52:23 INFO - Running pre-action listener: _resource_record_pre_action 12:52:23 INFO - Running main action method: install 12:52:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:52:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:52:24 INFO - Reading from file tmpfile_stdout 12:52:24 INFO - Detecting whether we're running mozinstall >=1.0... 12:52:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:52:24 INFO - Reading from file tmpfile_stdout 12:52:24 INFO - Output received: 12:52:24 INFO - Usage: mozinstall [options] installer 12:52:24 INFO - Options: 12:52:24 INFO - -h, --help show this help message and exit 12:52:24 INFO - -d DEST, --destination=DEST 12:52:24 INFO - Directory to install application into. [default: 12:52:24 INFO - "/builds/slave/test"] 12:52:24 INFO - --app=APP Application being installed. [default: firefox] 12:52:24 INFO - mkdir: /builds/slave/test/build/application 12:52:24 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'] 12:52:24 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 12:52:58 INFO - Reading from file tmpfile_stdout 12:52:58 INFO - Output received: 12:52:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:52:58 INFO - Running post-action listener: _resource_record_post_action 12:52:58 INFO - [mozharness: 2016-09-04 19:52:58.455054Z] Finished install step (success) 12:52:58 INFO - [mozharness: 2016-09-04 19:52:58.455199Z] Running run-tests step. 12:52:58 INFO - Running pre-action listener: _resource_record_pre_action 12:52:58 INFO - Running main action method: run_tests 12:52:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:52:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:52:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:52:58 INFO - 2016-09-04 12:52:58.469 screenresolution[1660:7736] starting screenresolution argv=screenresolution get 12:52:58 INFO - 2016-09-04 12:52:58.472 screenresolution[1660:7736] Display 0: 1600x1200x32@60 12:52:58 INFO - 2016-09-04 12:52:58.478 screenresolution[1661:7739] starting screenresolution argv=screenresolution list 12:52:58 INFO - Available Modes on Display 0 12:52:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:52:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:52:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:52:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:52:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:52:58 INFO - Intel Iris: 12:52:58 INFO - Chipset Model: Intel Iris 12:52:58 INFO - Type: GPU 12:52:58 INFO - Bus: Built-In 12:52:58 INFO - VRAM (Dynamic, Max): 1536 MB 12:52:58 INFO - Vendor: Intel (0x8086) 12:52:58 INFO - Device ID: 0x0a2e 12:52:58 INFO - Revision ID: 0x0009 12:52:58 INFO - Displays: 12:52:58 INFO - Display: 12:52:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:52:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:52:58 INFO - Display Serial Number: 1600x1200 60 12:52:58 INFO - Main Display: Yes 12:52:58 INFO - Mirror: Off 12:52:58 INFO - Online: Yes 12:52:58 INFO - Rotation: Supported 12:52:58 INFO - Return code: 0 12:52:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:52:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:52:58 INFO - Python 2.7.3 12:52:58 INFO - Return code: 0 12:52:58 INFO - grabbing minidump binary from tooltool 12:52:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:58 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 0x10be5cd30>, '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 0x10bbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be54b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:52:58 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 12:52:58 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 12:52:58 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 12:52:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:52:58 INFO - Return code: 0 12:52:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:52:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:52:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:52:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:52:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:52:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:52:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0181 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:52:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zhqEt3l95f/Render', 12:52:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:52:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:52:58 INFO - 'HOME': '/Users/cltbld', 12:52:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:52:58 INFO - 'LOGNAME': 'cltbld', 12:52:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:52:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:52:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:58 INFO - 'MOZ_NO_REMOTE': '1', 12:52:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:52:58 INFO - 'NO_EM_RESTART': '1', 12:52:58 INFO - 'PAGER': '/bin/cat', 12:52:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:52:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:58 INFO - 'PWD': '/builds/slave/test', 12:52:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:52:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:52:58 INFO - 'SHELL': '/bin/bash', 12:52:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQFAozgsU1/Listeners', 12:52:58 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 12:52:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:52:58 INFO - 'USER': 'cltbld', 12:52:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:52:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:52:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:58 INFO - 'XPC_FLAGS': '0x0', 12:52:58 INFO - 'XPC_SERVICE_NAME': '0', 12:52:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:52:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:52:59 INFO - mozversion application_buildid: 20160904103751 12:52:59 INFO - mozversion application_changeset: dbe4b47941c7b3d6298a0ead5e40dd828096c808 12:52:59 INFO - mozversion application_display_name: Nightly 12:52:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:52:59 INFO - mozversion application_name: Firefox 12:52:59 INFO - mozversion application_remotingname: firefox 12:52:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:52:59 INFO - mozversion application_vendor: Mozilla 12:52:59 INFO - mozversion application_version: 51.0a1 12:52:59 INFO - mozversion platform_buildid: 20160904103751 12:52:59 INFO - mozversion platform_changeset: dbe4b47941c7b3d6298a0ead5e40dd828096c808 12:52:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:52:59 INFO - mozversion platform_version: 51.0a1 12:52:59 INFO - using testdate: 1473018779 12:52:59 INFO - actual date: 1473018779 12:52:59 INFO - starting webserver on 'localhost:49272' 12:52:59 INFO - SUITE-START | Running 2 tests 12:52:59 INFO - TEST-START | dromaeo_css 12:52:59 INFO - operating with platform_type : mac_ 12:52:59 INFO - Initialising browser for dromaeo_css test... 12:52:59 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpz5L1mC/profile http://localhost:49272/getInfo.html) 12:53:02 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 12:53:02 INFO - PROCESS | 1671 | colorDepth:24 12:53:02 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 12:53:02 INFO - PROCESS | 1671 | __metrics 12:53:02 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 12:53:02 INFO - TEST-INFO | 1671: exit 0 12:53:02 INFO - Browser initialized. 12:53:02 INFO - Running cycle 1/1 for dromaeo_css test... 12:53:02 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpz5L1mC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:53:14 INFO - PROCESS | 1675 | RSS: Main: 243843072 12:53:14 INFO - PROCESS | 1675 | 12:54:04 INFO - PROCESS | 1675 | 1473018844558 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] 12:56:16 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 12:56:18 INFO - PROCESS | 1675 | RSS: Main: 262590464 12:56:18 INFO - PROCESS | 1675 | 12:59:20 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 12:59:21 INFO - PROCESS | 1675 | RSS: Main: 270340096 12:59:21 INFO - PROCESS | 1675 | 13:02:23 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 13:02:23 INFO - PROCESS | 1675 | RSS: Main: 272994304 13:02:23 INFO - PROCESS | 1675 | 13:05:25 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 13:05:26 INFO - PROCESS | 1675 | RSS: Main: 268419072 13:05:26 INFO - PROCESS | 1675 | 13:08:29 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 13:08:29 INFO - PROCESS | 1675 | RSS: Main: 273752064 13:08:29 INFO - PROCESS | 1675 | 13:11:32 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 13:11:32 INFO - PROCESS | 1675 | RSS: Main: 276717568 13:11:32 INFO - PROCESS | 1675 | 13:11:32 INFO - PROCESS | 1675 | __start_tp_report 13:11:32 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 13:11:32 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 13:11:32 INFO - PROCESS | 1675 | |i|pagename|runs| 13:11:32 INFO - PROCESS | 1675 | |0;dojo.html;2981;3367.1413789483677;3369.1913940654235;3402.302527981764;3425.5718035245586;5587.804426845061;5646.124850648151;5667.319921609407;5675.801346952852;5753.798617048405;5623.718814059301;5722.914156287648;5739.081746920494;5741.196232527439;5833.708314584264;5129.307543481638;5226.24219488174;5238.00477909198;5274.129768588197;5327.600429967749;4031.778252196128;4277.951901784068;4365.952171478838;4431.556844315559;5376.650517716355;5066.392032956032;5092.414372347191;5173.81891633791;5174.456682048369;5312.893742125155;5127.974360128175;5140.485951404829;5151.562117220041;5163.457836927106;5218.739063046833;5195.974020129913;5200.271961925334;5230.163173892159;5231.503007214308;5267.499587539178;5493.434371204187;5588.3573389060375;5697.829065128053;5766.826995190151;5784.219130417382;6124.816255512297;6132.63086471492;6144.354842741493;6150.923588372062;6160.5687601867885;6164.907526387108;6211.4409703126485;6213.844653883644;6241.719122639447;6241.906371404432;5686.3744988051285;5802.4777770000055;5832.329282132567;5833.066709326487;5852.531797456193;6307.905381419282;6313.652749098844;6321.778737744157;6326.715297811609;6331.683415829282;6166.383361663888;6187.907181392283;6198.442140207403;6205.379462053758;6212.378762123752;6130.019196928559;6135.110179431545;6142.529470848956;6174.38256174388;6215.192025036717;6172.259328880473;6210.378962103753;6213.440790328892;6227.532935029891;6229.906551401733;6168.383161683885;6227.844303892349;6237.688115594202;6250.468710159596;6250.468710159687;4858.951410485921;4884.902301953941;4884.9741554274;4900.803967841318;4983.252512123138;4372.1692878353015;4374.19077470669;4375.540568240289;4391.407160033418;4432.955670443319;5722.599418040781;5810.854728631733;5816.709164541756;5832.1834943108;5833.066709326487;6137.652792202601;6142.078688196717;6154.015357542772;6168.784092556739;6206.844828879224;5805;5807.303123625107;5808.564357673192;5833.9790536656765;5846.1230815377185;5411.675299482044;5416.0792665247;5437.646552974044;5440.863978400572;5444.564434845203;6186.257649082139;6222.595531290452;6261.3112557617715;6284.8428789278805;6410.294867564564;5191.22131680251;6181.783637572664;6212.347703491068;6223.844403889939;6228.34602366745;6310.148130002391;6323.620582765049;6388.4569811566535;6389.073584330339;6399.776007839703;6046.365131661112;6106.157323464254;6125.173101631401;6138.419052470285;6161.568690191643;6186.690665466798;6192.566520343533;6202.658853762906;6205.565610407228;6226.034964580497;6135.84660383494;6155.230596175478;6163.322034576104;6169.290531588925;6169.6915154241215;5961.672108034099;6087.4521293083835;6091.9086213707715;6133.754649813957;6133.98789199777;4331.328644060176;5797.927383434078;6011.729472173675;6030.276366836008;6063.272407311151;4940.431850337257;4953.578945789648;6068.9089663654195;6081.118237855575;6109.511239100772;5807.767689292381;5968.791092311748;5990.4908082813445;6036.9698151508965;6069.605475644069 13:11:32 INFO - PROCESS | 1675 | |1;ext.html;491.383313941004;508.61853609792644;517.8731210853341;533.8211699080811;555.4445554445555;30441.54337684934;41463.79268103659;41583;41614.791926040365;41795.791021044926;41604;42152.15695686085;42180.156396872044;42220.15559688804;42327.78836105823;8131.186881311866;9831.31180817343;9854.260930430211;9856.26078044146;9899.01009899008;9648.276379271547;9810.656627018056;9810.803783924317;9825.459599722013;9868.062534059252;9820.607175712963;9826.852597211047;9828.655997040105;9843.852342214872;9849.359791613522;9745.463999480062;9752.26857985654;9770.316077874551;9808.411495310304;9822.950885245527;9752.707418777449;9811.607535698564;9843;9863.852042219372;9873.703788886272;40145.59854401448;40363;40396.99007524806;40495.19009619791;40520.594794051976;3333.5333053372306;3341.431956567365;3394.6944774970125;3418.5555877735983;3445.5514422718625;30889.455527223774;31164.68835311663;31269.687303126906;31926.361472770415;32512.02463926086;3218.7812187812187;3245.837708114585;3285.3593549257903;3294.159989202738;3309.9172520687175;488887;489392;492904;497248;498459;59634.80730385368;59851;59989.40010599838;60020;60109.69945150334;33803.32393352119;34666;34764;35969.64030359663;61599;16454.095024773513;16590.08754518513;16627;16661.25024373906;16672.583185420463;12769.233845969271;12862.935685321701;12879.935600321938;12903.483860645469;12918.547850825176;19110.904445477685;19142.234310627697;19205.423837284903;19271.13279902408;19306.13122409495;6616;7827.765167044998;7958.243966823143;7962.372417102342;8021.879671804928;805.6012273924327;888.7378223424106;1021.4535223655308;1094.458243169636;1096.8848270931596;23788.167414140422;29927;30076.548851767242;30885.845570772002;31175.84412077925;914.9588268527932;949.2595775295281;1057.5399701130034;1100.4552746390589;1102.8070087734613;15472.5358239253;15672.451464198926;15834.366625334858;16113.469220424042;16270;677156;677850;684084.318313631;686911;686934;57028.42971570231;58147.8370432589;58185.418145818;58215;58277.417225828045;1200.1598880783486;1342.9731405371838;1376.5801430563774;1404.9999999999795;1412.7103943691518;24588.50822983566;28803.711962880105;30026.54960175643;30039.399212016073;30606.08181754551;53892;55482.16776748433;55582.610434737835;58758.886877918136;59022;628475;632285;634027;634114;635344;42210.78894605507;42413.363799542414;42587.7870610645;42837.5716242846;43102.92242693958;45282.09435811331;45522.710242807356;45621.54378456173;45671.85820354518;45756.542434575225;39059;39094.21811563696;39562.406563900986;39711.80144099261;40292;38828;39032.609673902894;40189;40396;40653;28914.710852892043;30273.545896812007;37421.251574967806;37851.62148378591;38634.6136538631;33396.1650958728;34759.47860782141;34816.90699172529;34880;35012;19883.701744473554;21838.90805459689;24167.516649666555;25950.61074083928;25996.841885731308 13:11:32 INFO - PROCESS | 1675 | |2;jquery.html;340.66444552116167;378.63461759402173;379.6564109480919;383.7007134435138;400.6994753934549;19411.02944852757;21024.57950840982;21144.89427552862;21532.354029379105;21738.02178901949;20681;20893;21068.68396974043;21236.088752012314;21285.25501607444;5905.232319798431;5954.13665018572;6069.1199776032445;6170.413810687977;6181.845453863667;4205.873823785291;5586.888262234753;6091.086337049456;6121.693915304239;6853.965730171367;6804.285550017251;6836.794896153124;6842.213145488258;6843.692033858463;6844.8973265401055;6509.772157974472;6734.090897728793;6759.425448836806;6780;6792.0830687857;5787.276590426197;5818.883622327529;5821.214136091616;5881.441263080001;5912.142739302777;18639.720404193948;25628.231153065437;25803.870980645166;25877.35306617331;25889.611655825178;2140.1118535807623;2157.6349768183763;2198.483356411242;2420.056178090546;2428.2229686500327;15479.380824766995;15743.606409839731;15843.287052082684;15938.920305398515;16477.83522164775;1865.4776662534512;1873.3536929759211;1886.990565047166;1926.1139875657213;1952.951176220606;81518.59240703817;106161.46919265432;108985.45507272492;109452;109486.9999999992;7903.604819759104;9189.63241470347;9794.118529332252;9829.754256143653;10181.33821301613;12175.817362739566;12269.38653067361;12337.383130843422;12338;12339.69150771238;7059.964700176466;7228.241034691387;7569.091708994956;8075.475094118863;8217.95891020541;8439.367047471464;8454.323654107657;8458.435189489965;8573.485590864568;8612.956935215283;3837.2325534893134;3842.6733727633464;3854.1713531590844;3868.709846761448;3987.7806720630642;1580.786593809821;1656.9502914912573;1674.4390629139375;1926.1139875657354;1980.237608520701;876.3909083187124;909.7998440343114;945.2579724915907;975.1808480875957;992.1764935103846;20770.376888693365;21316.573668526544;21411;21542.13831446756;21611;913.7487191022389;936.7892224249625;962.7544976031207;963.6579014449909;991.7024892532212;8873.866891996626;10892.782144357068;10994.505247263893;11233.438328083563;11388;397880;552493;553611;554784;574372;129012;130490.34754826355;131223;134273;141622;953.4279432340679;958.942463452195;985.1773768903046;993.2252842782497;994.7612572982576;14067.929660351632;16076.839231608003;18191;18994.715079274098;19228;55649.721751390985;56399;56572.71713641405;57222.855542889745;57915.13127303009;77234.2276577227;80267;82053;82456.58771706103;82736;6443.871122577616;7268.41852651776;7303.415726741954;7384.96307518459;7580.772576822704;11396.772064558829;11849.407529623657;12884.742305153657;12987.480500779862;13006.983917492804;10796.352218866894;10848.186386020921;10917.181211409017;11010.39442830652;11249.943750281196;10862.456877156268;10883.727906802393;11086.057685096857;11158.49786759614;11269.549218031187;11233.38216398106;11379.577552805898;11596.47815848272;11680.532778688757;11714.76570468603;6820;7370.189279179393;7384.926150738639;7541.4343924207005;7544.886826697494;10530.526126324181;11237.71905702364;11242.325460472397;11376.203665743318;11529.365884876412 13:11:32 INFO - PROCESS | 1675 | |3;mootools.html;1318.2551858200115;1436.2029073864005;1623.6427985843118;1854.4807453912892;1866.7573215481984;7968.402369822257;8448.704295349657;8575.442596231256;9027.187553120219;9039.548022598869;8632.48205107694;8805;8823.985241697206;8838.33712471564;8864.335174861879;2308.1113771198125;2312.560613483441;2331.6968794056747;2332.6384410416413;2334.3697201755517;2352.9764702352927;2378.643203519477;2378.857268563892;2390.2470721722602;2392.270357540949;2336.1823361823313;2340.6137987232087;2369.9644505332435;2397.6283676030157;2401.7238017627933;2350.8589484630893;2357.210334537932;2371.3834403055157;2377.215518878766;2380.726216485109;2335.1943579465055;2344.296710986714;2352.7294361148515;2354.117206047732;2354.6703461515403;8871.24594409476;8914.687985920527;8932;8933.955330223307;8941.329400295004;5249.107651699221;5392.4337944515655;5404.91892621611;5406.702631355274;5411.594130440233;3612.313660404515;3677.3932301170275;3714.7771133732062;3719.8326075326677;3749.8125093745475;915.578833736482;1065.7282392989816;1067.813132701774;1071.3893080943865;1085.3270971997413;11497.482613282422;11569.479373428216;11603.187776855624;11627.360495172763;11685.649430517013;4545.727256364628;4618.1918164321105;4796.616270698336;4812;4832.855014349575;9852.655157069612;9902.207823374112;9958.950205249072;9961.452120133317;9971.900280997243;5337.572994160458;5372.70450125239;5374.167004114369;5376.086065368897;5405.594580406407;4375.562443755599;4735.313379559944;4872.975635121802;4899.510048995072;4923.252245452884;2987.9542160244177;3291.835408229579;3448.827558622109;3892.3188442022533;4053.2704113259897;1068.8610480637624;1295.1775622479793;1295.4688577683105;1303.582853486894;1308.188922867828;815.0504662068623;922.9538523073953;924.4083786376724;936.7751739582451;946.129560804062;5701.657900525982;5814.098814683731;5859.5019423349495;5989.790357337472;6042.969785151046;379.4156998222761;428.14157613984383;454.6794509870533;455.4602795687055;459.5794847714371;3927.626875446828;3977.3835055566433;3999.060220848098;4042.6765858731233;4052.7365721228025;10401.323913945569;10681.83977240342;10805.973432523897;10829.620963266249;10848.837267440995;10747.946260268649;10762.623308184333;10771.730706732395;10795.730106747395;10805.351678899291;7701.730439434593;8086.3935204861045;8181.263686268265;8278.006639203335;8278.668853245803;5926.4666180044005;5997.521437566288;6013.819585412444;6030.276366836008;6193.907091393723;9260.583273752833;10038.949805250926;10078;10082.899171008195;10089.747756306151;9959.302848800515;10139.442330671589;10184.949075254577;10225.948870255601;10459.21555883327;7523.3229009389415;7662.157162712208;7743.651535680795;7756.3019328260725;7766.184550622329;8295.336373090018;8313.210245026712;8342.958285208535;8389.286910612665;8398.87401688963;7110.416905023521;7357.5953322567775;7487.138979017483;7510.324070833652;7572.507786993828;7421.4062874968795;7422.4433167513735;7429.7399591014055;7439.256074392518;7553.584552849646;6826.214985276757;6827.419669328163;6882.277360877036;6897.827554311182;6927.134108236471;4849.660523763303;4914.410270767531;5003.224500202475;5059.4315762113865;5104.540591346648;4928.901421971612;5058.848234552969;5086.143557601523;5207.27098206259;5212.843614691566 13:11:32 INFO - PROCESS | 1675 | |4;prototype.html;263.2733655111891;296.22389339929384;311.7973317343726;319.6643524299486;321.193803553082;4032.790162951114;4685.390899183106;5311.893762124755;5402.8919421611545;5611.663300201991;4252.957470425295;4754.524547545244;5193.16909294513;5402.756875940582;5418.83743487695;3962.9009274768146;4081.8979525511804;4097.713160078795;4163.5836416358425;4169.852525949139;3018.381231847466;3502.562179727534;4069.4302797608357;4118;4179.9791001045105;3808.1812410331772;3848.538175418954;4195.538490766014;4215.85244516442;4237.4279472271155;3319.319539494422;3594.658507441789;3842.8078596070086;4220.915581688364;4232.365145228225;2856.871440785149;3138.4350816852957;3318.5685860837934;3648.5804132524836;3675.9339791460443;4601.83893563727;5321.680699158024;5382.865428364283;5385.461453854622;5390.676559406448;4501.977490112561;4848.2727590861305;4848.903021939542;4854.854354369375;4872.756362181876;5808.896309701143;5813.651180929158;5846.590738648296;5873.035700901917;5946.672932988684;3373.8650453981813;3526.2066035142143;4037.9596204037875;4067.3898915162963;4079.2045551117544;5036.967421678547;5507.752151153208;5973.387185459945;6010.639361638316;6110.816675499697;3696.5194524711615;4080.18396320737;4083.4691490106097;4102.466679331667;4105.97947010263;3834.8687137294564;4146.398772178018;4161.500619925629;4171.791410429466;4192.727472714325;4556.635469162459;4834.20235661112;4853.835079580946;4870.805167793319;4875.9512404875495;4621.376114224604;5048.469910659402;5803.955288048108;5827.271591051119;5867.970660146672;1248.9072061945797;1289.3295486347047;1347.0166778251735;1355.9186448813102;1442.776369662704;800.72284705894;820.8481430935296;831.846108469935;894.8478758610921;897.3628723606182;5163.638545301793;5366.195070739421;5397.4062853086125;5404.216388623628;5412.404635490093;5305.65513241638;5451.727413629382;5458.563314934796;5499.285092937893;5993.160957465958;3750.7937063461227;4838.588719958843;5372.811951581676;5399.055165346049;5509.256250406224;3082.8458577071056;3179.1734149121394;3179.5230715392877;3214.035789263212;3247.253131779704;5079.74601269935;5901.704914754245;6097.3292937776805;6210.565260431817;6271.717772700148;5872.471477566954;6125.387461253928;6180.805813935988;6204.193454850841;6238.1266622672865;5479.369872464637;5828.1917418130215;6106.297775755845;6192.690365481708;6281.497480201573;5250.65870718402;5784.797532086525;5940.227770389822;5981.66714981149;6001.309849367379;5424.728763561885;5886.440093375345;5921.940780592139;5930.140129681258;5932.525397968066;5382.757775900016;5870.9412905870395;6249.468795152463;6256;6286.811395658137;2943.646762388527;3127.390158918989;3135.623725152905;3347.163209197701;3464.4976478410995;4349.369341445409;4530.870490869286;4871.075925009673;5067.403767813127;5130.050940576006;4002.9799851000557;4240.346264956608;4328.273187891608;4792.065547218259;4997.450280469218;4628.791704373379;4847.3698419205275;4934.333864928332;4956.727379994135;5021;3949;4140.254754144163;4510.300903359985;4677.625789936729;4912.680675756064;4991.700497970133;5253.632245742761;5511.22842802016;5600.663960162403;5785.884282314414;5298.3508245877065;5653.265075540154;5669.461401166883;5683.460071293221;5702.403327068261 13:11:32 INFO - PROCESS | 1675 | |5;yui.html;659.937305955934;671.6943790575287;683.8666460040292;691.529759763361;707.9858402831941;12901.612951611454;15133.243337833106;16207.270672819723;16236.675266494693;16263.024218546896;14647;14707.338169782386;14805.92597037016;14862.925685371585;14868.330925108368;677.502036003537;847.2882778466087;849.452103393311;870.6430363550945;872.7905302727331;852.2415050604935;862.1163307609687;863.9308855291594;866.6100254885326;869.4739682492096;933.593886659302;938.5119737736374;940.1538615246264;942.1850099663776;947.999860147544;910.9863352049724;933.0529512544772;934.1919239857508;935.4434111703594;940.4028441939383;669.6919417068106;676.1210426445601;693.7051753004953;696.3176087434342;696.7874798186601;15135.697286054326;15144.621384465367;15252.38990440381;15258.160801155933;15298.311575979107;14633.634159146106;14749.852501474956;14804.777928331083;14881.10713357202;15074.24628768568;11606.36165010924;11647.59233426826;11754.529818807237;11762.58830940913;11774.116941229442;3771.396576547739;3832.7891965941863;3837.76973381598;3989.5412027616617;3991.720579559432;110876;111810.44094779473;112777.30834037496;112940;117602.41198794037;10967.725806854733;11043.418823739177;11072.778544429228;11218.102551795837;11356.602518911799;23535.646965295535;23750.168744093873;23777.643335349985;23785.643215351785;23789.167379141993;7961.562114083781;7963.044434667877;7993.800154996055;7995.440319177718;8130.268275855202;4431.731275389085;4456.732596044248;4465.866024019285;4498;4499.415076040159;3088.660247372787;3587.8385472653795;3666.1018050577777;3667.4132138857653;3723.478712980185;512.4107276631903;607.6931149769426;607.7508221629199;611.8466690288838;611.9204503414528;531.0918329656331;554.8210053636024;592.5896366647438;666.6933210723025;667.7912977510721;9074.604385695438;9361;9407.952960235156;9448.574814133242;9466.763330916645;515.8452464260782;629.8992161254178;663.575311800448;665.1220389086457;670.8356452669127;4457.977710111429;4572.565606267399;4629.722216667011;4641.535846415399;4660.487346391894;158930;167603;172344;174231;175203;57329.713351432976;76207;78892.21107788963;92158;114217.86494553233;66334.6733065336;68155.97766033513;81068.59465702633;89738.55130724305;117886;7847.332976696816;7916.071053710164;9277.48973806447;9877.802443950937;9963.103320701171;45644.77177614091;57609.711951439975;59684.70157649184;61183.38816611955;62528.74942501215;75487.62256188683;82815;85218.57390713006;87552;88469;9333.813323733351;11069.83395249088;11276.9436152822;11454.484548195185;12164.965977891978;12825.871741282468;14280.642983925485;14744.705105897607;15034.774478382615;15239.923800380928;12937.482500700244;13324.276619796628;13586.456541738615;13933.651658708208;14268.14391136535;13314.800277996032;13943.930280348533;13971;14159.575212743634;14457.204853733145;13398.531051413154;13517.86482135166;14039.929800350932;14176.220307882753;14206.715865682836;11085.667429977113;11130.387828669502;11384.943075284571;11392.544298227978;11880.940595296968;9931.602735890483;10300.369639972685;10381.325213861377;10672.30630009047;11418.477935452993 13:11:32 INFO - PROCESS | 1675 | __end_tp_report 13:11:32 INFO - PROCESS | 1675 | __start_cc_report 13:11:32 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,381 13:11:32 INFO - PROCESS | 1675 | __end_cc_report 13:11:32 INFO - PROCESS | 1675 | __startTimestamp1473019892969__endTimestamp 13:11:32 INFO - PROCESS | 1675 | 13:11:32 INFO - PROCESS | 1675 | ------- Summary: start ------- 13:11:32 INFO - PROCESS | 1675 | Number of tests: 6 13:11:32 INFO - PROCESS | 1675 | 13:11:32 INFO - PROCESS | 1675 | [#0] dojo.html Cycles:165 Average:5693.64 Median:5968.79 stddev:690.74 (11.6%) stddev-sans-first:659.27 13:11:32 INFO - PROCESS | 1675 | Values: 2981.0 3367.1 3369.2 3402.3 3425.6 5587.8 5646.1 5667.3 5675.8 5753.8 5623.7 5722.9 5739.1 5741.2 5833.7 5129.3 5226.2 5238.0 5274.1 5327.6 4031.8 4278.0 4366.0 4431.6 5376.7 5066.4 5092.4 5173.8 5174.5 5312.9 5128.0 5140.5 5151.6 5163.5 5218.7 5196.0 5200.3 5230.2 5231.5 5267.5 5493.4 5588.4 5697.8 5766.8 5784.2 6124.8 6132.6 6144.4 6150.9 6160.6 6164.9 6211.4 6213.8 6241.7 6241.9 5686.4 5802.5 5832.3 5833.1 5852.5 6307.9 6313.7 6321.8 6326.7 6331.7 6166.4 6187.9 6198.4 6205.4 6212.4 6130.0 6135.1 6142.5 6174.4 6215.2 6172.3 6210.4 6213.4 6227.5 6229.9 6168.4 6227.8 6237.7 6250.5 6250.5 4859.0 4884.9 4885.0 4900.8 4983.3 4372.2 4374.2 4375.5 4391.4 4433.0 5722.6 5810.9 5816.7 5832.2 5833.1 6137.7 6142.1 6154.0 6168.8 6206.8 5805.0 5807.3 5808.6 5834.0 5846.1 5411.7 5416.1 5437.6 5440.9 5444.6 6186.3 6222.6 6261.3 6284.8 6410.3 5191.2 6181.8 6212.3 6223.8 6228.3 6310.1 6323.6 6388.5 6389.1 6399.8 6046.4 6106.2 6125.2 6138.4 6161.6 6186.7 6192.6 6202.7 6205.6 6226.0 6135.8 6155.2 6163.3 6169.3 6169.7 5961.7 6087.5 6091.9 6133.8 6134.0 4331.3 5797.9 6011.7 6030.3 6063.3 4940.4 4953.6 6068.9 6081.1 6109.5 5807.8 5968.8 5990.5 6037.0 6069.6 13:11:32 INFO - PROCESS | 1675 | 13:11:32 INFO - PROCESS | 1675 | [#1] ext.html Cycles:180 Average:72962.30 Median:41058.40 stddev:162850.23 (396.6%) stddev-sans-first:163216.16 13:11:32 INFO - PROCESS | 1675 | Values: 491.4 508.6 517.9 533.8 555.4 30441.5 41463.8 41583.0 41614.8 41795.8 41604.0 42152.2 42180.2 42220.2 42327.8 8131.2 9831.3 9854.3 9856.3 9899.0 9648.3 9810.7 9810.8 9825.5 9868.1 9820.6 9826.9 9828.7 9843.9 9849.4 9745.5 9752.3 9770.3 9808.4 9823.0 9752.7 9811.6 9843.0 9863.9 9873.7 40145.6 40363.0 40397.0 40495.2 40520.6 3333.5 3341.4 3394.7 3418.6 3445.6 30889.5 31164.7 31269.7 31926.4 32512.0 3218.8 3245.8 3285.4 3294.2 3309.9 488887.0 489392.0 492904.0 497248.0 498459.0 59634.8 59851.0 59989.4 60020.0 60109.7 33803.3 34666.0 34764.0 35969.6 61599.0 16454.1 16590.1 16627.0 16661.3 16672.6 12769.2 12862.9 12879.9 12903.5 12918.5 19110.9 19142.2 19205.4 19271.1 19306.1 6616.0 7827.8 7958.2 7962.4 8021.9 805.6 888.7 1021.5 1094.5 1096.9 23788.2 29927.0 30076.5 30885.8 31175.8 915.0 949.3 1057.5 1100.5 1102.8 15472.5 15672.5 15834.4 16113.5 16270.0 677156.0 677850.0 684084.3 686911.0 686934.0 57028.4 58147.8 58185.4 58215.0 58277.4 1200.2 1343.0 1376.6 1405.0 1412.7 24588.5 28803.7 30026.5 30039.4 30606.1 53892.0 55482.2 55582.6 58758.9 59022.0 628475.0 632285.0 634027.0 634114.0 635344.0 42210.8 42413.4 42587.8 42837.6 43102.9 45282.1 45522.7 45621.5 45671.9 45756.5 39059.0 39094.2 39562.4 39711.8 40292.0 38828.0 39032.6 40189.0 40396.0 40653.0 28914.7 30273.5 37421.3 37851.6 38634.6 33396.2 34759.5 34816.9 34880.0 35012.0 19883.7 21838.9 24167.5 25950.6 25996.8 13:11:32 INFO - PROCESS | 1675 | 13:11:32 INFO - PROCESS | 1675 | [#2] jquery.html Cycles:180 Average:32947.90 Median:379.15 stddev:89186.42 (23523.0%) stddev-sans-first:89403.00 13:11:32 INFO - PROCESS | 1675 | Values: 340.7 378.6 379.7 383.7 400.7 19411.0 21024.6 21144.9 21532.4 21738.0 20681.0 20893.0 21068.7 21236.1 21285.3 5905.2 5954.1 6069.1 6170.4 6181.8 4205.9 5586.9 6091.1 6121.7 6854.0 6804.3 6836.8 6842.2 6843.7 6844.9 6509.8 6734.1 6759.4 6780.0 6792.1 5787.3 5818.9 5821.2 5881.4 5912.1 18639.7 25628.2 25803.9 25877.4 25889.6 2140.1 2157.6 2198.5 2420.1 2428.2 15479.4 15743.6 15843.3 15938.9 16477.8 1865.5 1873.4 1887.0 1926.1 1953.0 81518.6 106161.5 108985.5 109452.0 109487.0 7903.6 9189.6 9794.1 9829.8 10181.3 12175.8 12269.4 12337.4 12338.0 12339.7 7060.0 7228.2 7569.1 8075.5 8218.0 8439.4 8454.3 8458.4 8573.5 8613.0 3837.2 3842.7 3854.2 3868.7 3987.8 1580.8 1657.0 1674.4 1926.1 1980.2 876.4 909.8 945.3 975.2 992.2 20770.4 21316.6 21411.0 21542.1 21611.0 913.7 936.8 962.8 963.7 991.7 8873.9 10892.8 10994.5 11233.4 11388.0 397880.0 552493.0 553611.0 554784.0 574372.0 129012.0 130490.3 131223.0 134273.0 141622.0 953.4 958.9 985.2 993.2 994.8 14067.9 16076.8 18191.0 18994.7 19228.0 55649.7 56399.0 56572.7 57222.9 57915.1 77234.2 80267.0 82053.0 82456.6 82736.0 6443.9 7268.4 7303.4 7385.0 7580.8 11396.8 11849.4 12884.7 12987.5 13007.0 10796.4 10848.2 10917.2 11010.4 11249.9 10862.5 10883.7 11086.1 11158.5 11269.5 11233.4 11379.6 11596.5 11680.5 11714.8 6820.0 7370.2 7384.9 7541.4 7544.9 10530.5 11237.7 11242.3 11376.2 11529.4 13:11:32 INFO - PROCESS | 1675 | 13:11:32 INFO - PROCESS | 1675 | [#3] mootools.html Cycles:180 Average:5717.34 Median:4886.24 stddev:3232.43 (66.2%) stddev-sans-first:3224.59 13:11:32 INFO - PROCESS | 1675 | Values: 1318.3 1436.2 1623.6 1854.5 1866.8 7968.4 8448.7 8575.4 9027.2 9039.5 8632.5 8805.0 8824.0 8838.3 8864.3 2308.1 2312.6 2331.7 2332.6 2334.4 2353.0 2378.6 2378.9 2390.2 2392.3 2336.2 2340.6 2370.0 2397.6 2401.7 2350.9 2357.2 2371.4 2377.2 2380.7 2335.2 2344.3 2352.7 2354.1 2354.7 8871.2 8914.7 8932.0 8934.0 8941.3 5249.1 5392.4 5404.9 5406.7 5411.6 3612.3 3677.4 3714.8 3719.8 3749.8 915.6 1065.7 1067.8 1071.4 1085.3 11497.5 11569.5 11603.2 11627.4 11685.6 4545.7 4618.2 4796.6 4812.0 4832.9 9852.7 9902.2 9959.0 9961.5 9971.9 5337.6 5372.7 5374.2 5376.1 5405.6 4375.6 4735.3 4873.0 4899.5 4923.3 2988.0 3291.8 3448.8 3892.3 4053.3 1068.9 1295.2 1295.5 1303.6 1308.2 815.1 923.0 924.4 936.8 946.1 5701.7 5814.1 5859.5 5989.8 6043.0 379.4 428.1 454.7 455.5 459.6 3927.6 3977.4 3999.1 4042.7 4052.7 10401.3 10681.8 10806.0 10829.6 10848.8 10747.9 10762.6 10771.7 10795.7 10805.4 7701.7 8086.4 8181.3 8278.0 8278.7 5926.5 5997.5 6013.8 6030.3 6193.9 9260.6 10038.9 10078.0 10082.9 10089.7 9959.3 10139.4 10184.9 10225.9 10459.2 7523.3 7662.2 7743.7 7756.3 7766.2 8295.3 8313.2 8343.0 8389.3 8398.9 7110.4 7357.6 7487.1 7510.3 7572.5 7421.4 7422.4 7429.7 7439.3 7553.6 6826.2 6827.4 6882.3 6897.8 6927.1 4849.7 4914.4 5003.2 5059.4 5104.5 4928.9 5058.8 5086.1 5207.3 5212.8 13:11:32 INFO - PROCESS | 1675 | 13:11:32 INFO - PROCESS | 1675 | [#4] prototype.html Cycles:180 Average:4523.54 Median:4894.32 stddev:1431.74 (29.3%) stddev-sans-first:1399.59 13:11:32 INFO - PROCESS | 1675 | Values: 263.3 296.2 311.8 319.7 321.2 4032.8 4685.4 5311.9 5402.9 5611.7 4253.0 4754.5 5193.2 5402.8 5418.8 3962.9 4081.9 4097.7 4163.6 4169.9 3018.4 3502.6 4069.4 4118.0 4180.0 3808.2 3848.5 4195.5 4215.9 4237.4 3319.3 3594.7 3842.8 4220.9 4232.4 2856.9 3138.4 3318.6 3648.6 3675.9 4601.8 5321.7 5382.9 5385.5 5390.7 4502.0 4848.3 4848.9 4854.9 4872.8 5808.9 5813.7 5846.6 5873.0 5946.7 3373.9 3526.2 4038.0 4067.4 4079.2 5037.0 5507.8 5973.4 6010.6 6110.8 3696.5 4080.2 4083.5 4102.5 4106.0 3834.9 4146.4 4161.5 4171.8 4192.7 4556.6 4834.2 4853.8 4870.8 4876.0 4621.4 5048.5 5804.0 5827.3 5868.0 1248.9 1289.3 1347.0 1355.9 1442.8 800.7 820.8 831.8 894.8 897.4 5163.6 5366.2 5397.4 5404.2 5412.4 5305.7 5451.7 5458.6 5499.3 5993.2 3750.8 4838.6 5372.8 5399.1 5509.3 3082.8 3179.2 3179.5 3214.0 3247.3 5079.7 5901.7 6097.3 6210.6 6271.7 5872.5 6125.4 6180.8 6204.2 6238.1 5479.4 5828.2 6106.3 6192.7 6281.5 5250.7 5784.8 5940.2 5981.7 6001.3 5424.7 5886.4 5921.9 5930.1 5932.5 5382.8 5870.9 6249.5 6256.0 6286.8 2943.6 3127.4 3135.6 3347.2 3464.5 4349.4 4530.9 4871.1 5067.4 5130.1 4003.0 4240.3 4328.3 4792.1 4997.5 4628.8 4847.4 4934.3 4956.7 5021.0 3949.0 4140.3 4510.3 4677.6 4912.7 4991.7 5253.6 5511.2 5600.7 5785.9 5298.4 5653.3 5669.5 5683.5 5702.4 13:11:32 INFO - PROCESS | 1675 | 13:11:32 INFO - PROCESS | 1675 | [#5] yui.html Cycles:180 Average:23268.61 Median:4444.23 stddev:37596.97 (846.0%) stddev-sans-first:37664.12 13:11:32 INFO - PROCESS | 1675 | Values: 659.9 671.7 683.9 691.5 708.0 12901.6 15133.2 16207.3 16236.7 16263.0 14647.0 14707.3 14805.9 14862.9 14868.3 677.5 847.3 849.5 870.6 872.8 852.2 862.1 863.9 866.6 869.5 933.6 938.5 940.2 942.2 948.0 911.0 933.1 934.2 935.4 940.4 669.7 676.1 693.7 696.3 696.8 15135.7 15144.6 15252.4 15258.2 15298.3 14633.6 14749.9 14804.8 14881.1 15074.2 11606.4 11647.6 11754.5 11762.6 11774.1 3771.4 3832.8 3837.8 3989.5 3991.7 110876.0 111810.4 112777.3 112940.0 117602.4 10967.7 11043.4 11072.8 11218.1 11356.6 23535.6 23750.2 23777.6 23785.6 23789.2 7961.6 7963.0 7993.8 7995.4 8130.3 4431.7 4456.7 4465.9 4498.0 4499.4 3088.7 3587.8 3666.1 3667.4 3723.5 512.4 607.7 607.8 611.8 611.9 531.1 554.8 592.6 666.7 667.8 9074.6 9361.0 9408.0 9448.6 9466.8 515.8 629.9 663.6 665.1 670.8 4458.0 4572.6 4629.7 4641.5 4660.5 158930.0 167603.0 172344.0 174231.0 175203.0 57329.7 76207.0 78892.2 92158.0 114217.9 66334.7 68156.0 81068.6 89738.6 117886.0 7847.3 7916.1 9277.5 9877.8 9963.1 45644.8 57609.7 59684.7 61183.4 62528.7 75487.6 82815.0 85218.6 87552.0 88469.0 9333.8 11069.8 11276.9 11454.5 12165.0 12825.9 14280.6 14744.7 15034.8 15239.9 12937.5 13324.3 13586.5 13933.7 14268.1 13314.8 13943.9 13971.0 14159.6 14457.2 13398.5 13517.9 14039.9 14176.2 14206.7 11085.7 11130.4 11384.9 11392.5 11880.9 9931.6 10300.4 10381.3 10672.3 11418.5 13:11:32 INFO - PROCESS | 1675 | -------- Summary: end -------- 13:11:32 INFO - PROCESS | 1675 | 13:11:33 INFO - TEST-INFO | 1675: exit 0 13:11:33 INFO - TEST-OK | dromaeo_css | took 1114605ms 13:11:33 INFO - TEST-START | kraken 13:11:33 INFO - operating with platform_type : mac_ 13:11:33 INFO - Initialising browser for kraken test... 13:11:33 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpde1FR0/profile http://localhost:49272/getInfo.html) 13:11:36 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 13:11:36 INFO - PROCESS | 1683 | colorDepth:24 13:11:36 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 13:11:36 INFO - PROCESS | 1683 | __metrics 13:11:36 ERROR - PROCESS | 1683 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 13:11:37 INFO - TEST-INFO | 1683: exit 0 13:11:37 INFO - Browser initialized. 13:11:37 INFO - Running cycle 1/1 for kraken test... 13:11:37 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpde1FR0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:11:49 INFO - PROCESS | 1685 | RSS: Main: 243560448 13:11:49 INFO - PROCESS | 1685 | 13:11:52 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 13:11:53 INFO - PROCESS | 1685 | RSS: Main: 256651264 13:11:53 INFO - PROCESS | 1685 | 13:11:55 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 13:11:56 INFO - PROCESS | 1685 | RSS: Main: 265416704 13:11:56 INFO - PROCESS | 1685 | 13:11:59 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 13:12:00 INFO - PROCESS | 1685 | RSS: Main: 263057408 13:12:00 INFO - PROCESS | 1685 | 13:12:02 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 13:12:03 INFO - PROCESS | 1685 | RSS: Main: 265424896 13:12:03 INFO - PROCESS | 1685 | 13:12:05 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:12:05 INFO - PROCESS | 1685 | RSS: Main: 265826304 13:12:05 INFO - PROCESS | 1685 | 13:12:08 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 13:12:09 INFO - PROCESS | 1685 | RSS: Main: 266133504 13:12:09 INFO - PROCESS | 1685 | 13:12:12 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 13:12:13 INFO - PROCESS | 1685 | RSS: Main: 266821632 13:12:13 INFO - PROCESS | 1685 | 13:12:16 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 13:12:16 INFO - PROCESS | 1685 | RSS: Main: 266842112 13:12:16 INFO - PROCESS | 1685 | 13:12:18 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:12:19 INFO - PROCESS | 1685 | RSS: Main: 266653696 13:12:19 INFO - PROCESS | 1685 | 13:12:21 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:12:21 INFO - PROCESS | 1685 | RSS: Main: 266567680 13:12:21 INFO - PROCESS | 1685 | 13:12:23 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:12:24 INFO - PROCESS | 1685 | RSS: Main: 266874880 13:12:24 INFO - PROCESS | 1685 | 13:12:27 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:12:28 INFO - PROCESS | 1685 | RSS: Main: 267030528 13:12:28 INFO - PROCESS | 1685 | 13:12:31 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:12:32 INFO - PROCESS | 1685 | RSS: Main: 266960896 13:12:32 INFO - PROCESS | 1685 | 13:12:33 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 13:12:34 INFO - PROCESS | 1685 | RSS: Main: 269021184 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | __start_tp_report 13:12:34 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 13:12:34 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 13:12:34 INFO - PROCESS | 1685 | |i|pagename|runs| 13:12:34 INFO - PROCESS | 1685 | |0;ai-astar;108;114;116;114;116;116;116;118;118;134 13:12:34 INFO - PROCESS | 1685 | |1;audio-beat-detection;128;134;176;190;114;135;140;148;170;113 13:12:34 INFO - PROCESS | 1685 | |2;audio-dft;199;208;215;217;198;200;204;198;197;193 13:12:34 INFO - PROCESS | 1685 | |3;audio-fft;68;80;76;148;82;115;82;61;142;51 13:12:34 INFO - PROCESS | 1685 | |4;audio-oscillator;70;71;122;70;69;75;108;64;74;110 13:12:34 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;101;90;133;132;94;75;139;131;91;86 13:12:34 INFO - PROCESS | 1685 | |6;imaging-darkroom;95;96;96;96;118;154;108;154;102;151 13:12:34 INFO - PROCESS | 1685 | |7;imaging-desaturate;113;154;93;93;111;151;115;90;90;91 13:12:34 INFO - PROCESS | 1685 | |8;json-parse-financial;56;57;57;59;58;58;58;57;57;57 13:12:34 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;68;102;105;110;80;75;73;74;69;71 13:12:34 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;88;84;90;84;85;88;98;111;83;89 13:12:34 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;155;138;136;144;142;171;144;149;171;152 13:12:34 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;168;176;241;229;173;175;247;242;170;244 13:12:34 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;71;69;70;67;69;70;69;70;68;67 13:12:34 INFO - PROCESS | 1685 | __end_tp_report 13:12:34 INFO - PROCESS | 1685 | __start_cc_report 13:12:34 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,965 13:12:34 INFO - PROCESS | 1685 | __end_cc_report 13:12:34 INFO - PROCESS | 1685 | __startTimestamp1473019954091__endTimestamp 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | ------- Summary: start ------- 13:12:34 INFO - PROCESS | 1685 | Number of tests: 14 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:117.00 Median:116.00 stddev:6.62 (5.7%) stddev-sans-first:6.16 13:12:34 INFO - PROCESS | 1685 | Values: 108.0 114.0 116.0 114.0 116.0 116.0 116.0 118.0 118.0 134.0 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:144.80 Median:144.00 stddev:26.12 (18.1%) stddev-sans-first:26.99 13:12:34 INFO - PROCESS | 1685 | Values: 128.0 134.0 176.0 190.0 114.0 135.0 140.0 148.0 170.0 113.0 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:202.90 Median:202.00 stddev:8.01 (4.0%) stddev-sans-first:8.37 13:12:34 INFO - PROCESS | 1685 | Values: 199.0 208.0 215.0 217.0 198.0 200.0 204.0 198.0 197.0 193.0 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:90.50 Median:72.00 stddev:33.27 (46.2%) stddev-sans-first:34.27 13:12:34 INFO - PROCESS | 1685 | Values: 68.0 80.0 76.0 148.0 82.0 115.0 82.0 61.0 142.0 51.0 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:83.30 Median:70.00 stddev:21.23 (30.3%) stddev-sans-first:21.97 13:12:34 INFO - PROCESS | 1685 | Values: 70.0 71.0 122.0 70.0 69.0 75.0 108.0 64.0 74.0 110.0 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:107.20 Median:80.50 stddev:23.84 (29.6%) stddev-sans-first:25.18 13:12:34 INFO - PROCESS | 1685 | Values: 101.0 90.0 133.0 132.0 94.0 75.0 139.0 131.0 91.0 86.0 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:117.00 Median:124.50 stddev:25.84 (20.8%) stddev-sans-first:26.15 13:12:34 INFO - PROCESS | 1685 | Values: 95.0 96.0 96.0 96.0 118.0 154.0 108.0 154.0 102.0 151.0 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:110.10 Median:90.00 stddev:24.47 (27.2%) stddev-sans-first:25.94 13:12:34 INFO - PROCESS | 1685 | Values: 113.0 154.0 93.0 93.0 111.0 151.0 115.0 90.0 90.0 91.0 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:57.50 stddev:0.84 (1.5%) stddev-sans-first:0.73 13:12:34 INFO - PROCESS | 1685 | Values: 56.0 57.0 57.0 59.0 58.0 58.0 58.0 57.0 57.0 57.0 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:82.70 Median:72.00 stddev:16.30 (22.6%) stddev-sans-first:16.40 13:12:34 INFO - PROCESS | 1685 | Values: 68.0 102.0 105.0 110.0 80.0 75.0 73.0 74.0 69.0 71.0 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:88.00 stddev:8.56 (9.7%) stddev-sans-first:9.05 13:12:34 INFO - PROCESS | 1685 | Values: 88.0 84.0 90.0 84.0 85.0 88.0 98.0 111.0 83.0 89.0 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:150.20 Median:150.50 stddev:12.42 (8.3%) stddev-sans-first:13.05 13:12:34 INFO - PROCESS | 1685 | Values: 155.0 138.0 136.0 144.0 142.0 171.0 144.0 149.0 171.0 152.0 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:206.50 Median:235.00 stddev:36.31 (15.4%) stddev-sans-first:35.73 13:12:34 INFO - PROCESS | 1685 | Values: 168.0 176.0 241.0 229.0 173.0 175.0 247.0 242.0 170.0 244.0 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.50 stddev:1.33 (1.9%) stddev-sans-first:1.20 13:12:34 INFO - PROCESS | 1685 | Values: 71.0 69.0 70.0 67.0 69.0 70.0 69.0 70.0 68.0 67.0 13:12:34 INFO - PROCESS | 1685 | -------- Summary: end -------- 13:12:34 INFO - PROCESS | 1685 | 13:12:34 INFO - TEST-INFO | 1685: exit 0 13:12:34 INFO - TEST-OK | kraken | took 61242ms 13:12:34 INFO - SUITE-END | took 1175s 13:12:35 INFO - Completed test suite (00:19:36) 13:12:35 INFO - javascript benchmark 13:12:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7302.20592847538, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2981.0, 3367.1413789483677, 3369.1913940654235, 3402.302527981764, 3425.5718035245586, 5587.804426845061, 5646.124850648151, 5667.319921609407, 5675.801346952852, 5753.798617048405, 5623.718814059301, 5722.914156287648, 5739.081746920494, 5741.196232527439, 5833.708314584264, 5129.307543481638, 5226.24219488174, 5238.00477909198, 5274.129768588197, 5327.600429967749, 4031.778252196128, 4277.951901784068, 4365.952171478838, 4431.556844315559, 5376.650517716355, 5066.392032956032, 5092.414372347191, 5173.81891633791, 5174.456682048369, 5312.893742125155, 5127.974360128175, 5140.485951404829, 5151.562117220041, 5163.457836927106, 5218.739063046833, 5195.974020129913, 5200.271961925334, 5230.163173892159, 5231.503007214308, 5267.499587539178, 5493.434371204187, 5588.3573389060375, 5697.829065128053, 5766.826995190151, 5784.219130417382, 6124.816255512297, 6132.63086471492, 6144.354842741493, 6150.923588372062, 6160.5687601867885, 6164.907526387108, 6211.4409703126485, 6213.844653883644, 6241.719122639447, 6241.906371404432, 5686.3744988051285, 5802.4777770000055, 5832.329282132567, 5833.066709326487, 5852.531797456193, 6307.905381419282, 6313.652749098844, 6321.778737744157, 6326.715297811609, 6331.683415829282, 6166.383361663888, 6187.907181392283, 6198.442140207403, 6205.379462053758, 6212.378762123752, 6130.019196928559, 6135.110179431545, 6142.529470848956, 6174.38256174388, 6215.192025036717, 6172.259328880473, 6210.378962103753, 6213.440790328892, 6227.532935029891, 6229.906551401733, 6168.383161683885, 6227.844303892349, 6237.688115594202, 6250.468710159596, 6250.468710159687, 4858.951410485921, 4884.902301953941, 4884.9741554274, 4900.803967841318, 4983.252512123138, 4372.1692878353015, 4374.19077470669, 4375.540568240289, 4391.407160033418, 4432.955670443319, 5722.599418040781, 5810.854728631733, 5816.709164541756, 5832.1834943108, 5833.066709326487, 6137.652792202601, 6142.078688196717, 6154.015357542772, 6168.784092556739, 6206.844828879224, 5805.0, 5807.303123625107, 5808.564357673192, 5833.9790536656765, 5846.1230815377185, 5411.675299482044, 5416.0792665247, 5437.646552974044, 5440.863978400572, 5444.564434845203, 6186.257649082139, 6222.595531290452, 6261.3112557617715, 6284.8428789278805, 6410.294867564564, 5191.22131680251, 6181.783637572664, 6212.347703491068, 6223.844403889939, 6228.34602366745, 6310.148130002391, 6323.620582765049, 6388.4569811566535, 6389.073584330339, 6399.776007839703, 6046.365131661112, 6106.157323464254, 6125.173101631401, 6138.419052470285, 6161.568690191643, 6186.690665466798, 6192.566520343533, 6202.658853762906, 6205.565610407228, 6226.034964580497, 6135.84660383494, 6155.230596175478, 6163.322034576104, 6169.290531588925, 6169.6915154241215, 5961.672108034099, 6087.4521293083835, 6091.9086213707715, 6133.754649813957, 6133.98789199777, 4331.328644060176, 5797.927383434078, 6011.729472173675, 6030.276366836008, 6063.272407311151, 4940.431850337257, 4953.578945789648, 6068.9089663654195, 6081.118237855575, 6109.511239100772, 5807.767689292381, 5968.791092311748, 5990.4908082813445, 6036.9698151508965, 6069.605475644069], "value": 5647.979084122656, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.383313941004, 508.61853609792644, 517.8731210853341, 533.8211699080811, 555.4445554445555, 30441.54337684934, 41463.79268103659, 41583.0, 41614.791926040365, 41795.791021044926, 41604.0, 42152.15695686085, 42180.156396872044, 42220.15559688804, 42327.78836105823, 8131.186881311866, 9831.31180817343, 9854.260930430211, 9856.26078044146, 9899.01009899008, 9648.276379271547, 9810.656627018056, 9810.803783924317, 9825.459599722013, 9868.062534059252, 9820.607175712963, 9826.852597211047, 9828.655997040105, 9843.852342214872, 9849.359791613522, 9745.463999480062, 9752.26857985654, 9770.316077874551, 9808.411495310304, 9822.950885245527, 9752.707418777449, 9811.607535698564, 9843.0, 9863.852042219372, 9873.703788886272, 40145.59854401448, 40363.0, 40396.99007524806, 40495.19009619791, 40520.594794051976, 3333.5333053372306, 3341.431956567365, 3394.6944774970125, 3418.5555877735983, 3445.5514422718625, 30889.455527223774, 31164.68835311663, 31269.687303126906, 31926.361472770415, 32512.02463926086, 3218.7812187812187, 3245.837708114585, 3285.3593549257903, 3294.159989202738, 3309.9172520687175, 488887.0, 489392.0, 492904.0, 497248.0, 498459.0, 59634.80730385368, 59851.0, 59989.40010599838, 60020.0, 60109.69945150334, 33803.32393352119, 34666.0, 34764.0, 35969.64030359663, 61599.0, 16454.095024773513, 16590.08754518513, 16627.0, 16661.25024373906, 16672.583185420463, 12769.233845969271, 12862.935685321701, 12879.935600321938, 12903.483860645469, 12918.547850825176, 19110.904445477685, 19142.234310627697, 19205.423837284903, 19271.13279902408, 19306.13122409495, 6616.0, 7827.765167044998, 7958.243966823143, 7962.372417102342, 8021.879671804928, 805.6012273924327, 888.7378223424106, 1021.4535223655308, 1094.458243169636, 1096.8848270931596, 23788.167414140422, 29927.0, 30076.548851767242, 30885.845570772002, 31175.84412077925, 914.9588268527932, 949.2595775295281, 1057.5399701130034, 1100.4552746390589, 1102.8070087734613, 15472.5358239253, 15672.451464198926, 15834.366625334858, 16113.469220424042, 16270.0, 677156.0, 677850.0, 684084.318313631, 686911.0, 686934.0, 57028.42971570231, 58147.8370432589, 58185.418145818, 58215.0, 58277.417225828045, 1200.1598880783486, 1342.9731405371838, 1376.5801430563774, 1404.9999999999795, 1412.7103943691518, 24588.50822983566, 28803.711962880105, 30026.54960175643, 30039.399212016073, 30606.08181754551, 53892.0, 55482.16776748433, 55582.610434737835, 58758.886877918136, 59022.0, 628475.0, 632285.0, 634027.0, 634114.0, 635344.0, 42210.78894605507, 42413.363799542414, 42587.7870610645, 42837.5716242846, 43102.92242693958, 45282.09435811331, 45522.710242807356, 45621.54378456173, 45671.85820354518, 45756.542434575225, 39059.0, 39094.21811563696, 39562.406563900986, 39711.80144099261, 40292.0, 38828.0, 39032.609673902894, 40189.0, 40396.0, 40653.0, 28914.710852892043, 30273.545896812007, 37421.251574967806, 37851.62148378591, 38634.6136538631, 33396.1650958728, 34759.47860782141, 34816.90699172529, 34880.0, 35012.0, 19883.701744473554, 21838.90805459689, 24167.516649666555, 25950.61074083928, 25996.841885731308], "value": 20145.606707523, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.66444552116167, 378.63461759402173, 379.6564109480919, 383.7007134435138, 400.6994753934549, 19411.02944852757, 21024.57950840982, 21144.89427552862, 21532.354029379105, 21738.02178901949, 20681.0, 20893.0, 21068.68396974043, 21236.088752012314, 21285.25501607444, 5905.232319798431, 5954.13665018572, 6069.1199776032445, 6170.413810687977, 6181.845453863667, 4205.873823785291, 5586.888262234753, 6091.086337049456, 6121.693915304239, 6853.965730171367, 6804.285550017251, 6836.794896153124, 6842.213145488258, 6843.692033858463, 6844.8973265401055, 6509.772157974472, 6734.090897728793, 6759.425448836806, 6780.0, 6792.0830687857, 5787.276590426197, 5818.883622327529, 5821.214136091616, 5881.441263080001, 5912.142739302777, 18639.720404193948, 25628.231153065437, 25803.870980645166, 25877.35306617331, 25889.611655825178, 2140.1118535807623, 2157.6349768183763, 2198.483356411242, 2420.056178090546, 2428.2229686500327, 15479.380824766995, 15743.606409839731, 15843.287052082684, 15938.920305398515, 16477.83522164775, 1865.4776662534512, 1873.3536929759211, 1886.990565047166, 1926.1139875657213, 1952.951176220606, 81518.59240703817, 106161.46919265432, 108985.45507272492, 109452.0, 109486.9999999992, 7903.604819759104, 9189.63241470347, 9794.118529332252, 9829.754256143653, 10181.33821301613, 12175.817362739566, 12269.38653067361, 12337.383130843422, 12338.0, 12339.69150771238, 7059.964700176466, 7228.241034691387, 7569.091708994956, 8075.475094118863, 8217.95891020541, 8439.367047471464, 8454.323654107657, 8458.435189489965, 8573.485590864568, 8612.956935215283, 3837.2325534893134, 3842.6733727633464, 3854.1713531590844, 3868.709846761448, 3987.7806720630642, 1580.786593809821, 1656.9502914912573, 1674.4390629139375, 1926.1139875657354, 1980.237608520701, 876.3909083187124, 909.7998440343114, 945.2579724915907, 975.1808480875957, 992.1764935103846, 20770.376888693365, 21316.573668526544, 21411.0, 21542.13831446756, 21611.0, 913.7487191022389, 936.7892224249625, 962.7544976031207, 963.6579014449909, 991.7024892532212, 8873.866891996626, 10892.782144357068, 10994.505247263893, 11233.438328083563, 11388.0, 397880.0, 552493.0, 553611.0, 554784.0, 574372.0, 129012.0, 130490.34754826355, 131223.0, 134273.0, 141622.0, 953.4279432340679, 958.942463452195, 985.1773768903046, 993.2252842782497, 994.7612572982576, 14067.929660351632, 16076.839231608003, 18191.0, 18994.715079274098, 19228.0, 55649.721751390985, 56399.0, 56572.71713641405, 57222.855542889745, 57915.13127303009, 77234.2276577227, 80267.0, 82053.0, 82456.58771706103, 82736.0, 6443.871122577616, 7268.41852651776, 7303.415726741954, 7384.96307518459, 7580.772576822704, 11396.772064558829, 11849.407529623657, 12884.742305153657, 12987.480500779862, 13006.983917492804, 10796.352218866894, 10848.186386020921, 10917.181211409017, 11010.39442830652, 11249.943750281196, 10862.456877156268, 10883.727906802393, 11086.057685096857, 11158.49786759614, 11269.549218031187, 11233.38216398106, 11379.577552805898, 11596.47815848272, 11680.532778688757, 11714.76570468603, 6820.0, 7370.189279179393, 7384.926150738639, 7541.4343924207005, 7544.886826697494, 10530.526126324181, 11237.71905702364, 11242.325460472397, 11376.203665743318, 11529.365884876412], "value": 9499.660397413663, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.2551858200115, 1436.2029073864005, 1623.6427985843118, 1854.4807453912892, 1866.7573215481984, 7968.402369822257, 8448.704295349657, 8575.442596231256, 9027.187553120219, 9039.548022598869, 8632.48205107694, 8805.0, 8823.985241697206, 8838.33712471564, 8864.335174861879, 2308.1113771198125, 2312.560613483441, 2331.6968794056747, 2332.6384410416413, 2334.3697201755517, 2352.9764702352927, 2378.643203519477, 2378.857268563892, 2390.2470721722602, 2392.270357540949, 2336.1823361823313, 2340.6137987232087, 2369.9644505332435, 2397.6283676030157, 2401.7238017627933, 2350.8589484630893, 2357.210334537932, 2371.3834403055157, 2377.215518878766, 2380.726216485109, 2335.1943579465055, 2344.296710986714, 2352.7294361148515, 2354.117206047732, 2354.6703461515403, 8871.24594409476, 8914.687985920527, 8932.0, 8933.955330223307, 8941.329400295004, 5249.107651699221, 5392.4337944515655, 5404.91892621611, 5406.702631355274, 5411.594130440233, 3612.313660404515, 3677.3932301170275, 3714.7771133732062, 3719.8326075326677, 3749.8125093745475, 915.578833736482, 1065.7282392989816, 1067.813132701774, 1071.3893080943865, 1085.3270971997413, 11497.482613282422, 11569.479373428216, 11603.187776855624, 11627.360495172763, 11685.649430517013, 4545.727256364628, 4618.1918164321105, 4796.616270698336, 4812.0, 4832.855014349575, 9852.655157069612, 9902.207823374112, 9958.950205249072, 9961.452120133317, 9971.900280997243, 5337.572994160458, 5372.70450125239, 5374.167004114369, 5376.086065368897, 5405.594580406407, 4375.562443755599, 4735.313379559944, 4872.975635121802, 4899.510048995072, 4923.252245452884, 2987.9542160244177, 3291.835408229579, 3448.827558622109, 3892.3188442022533, 4053.2704113259897, 1068.8610480637624, 1295.1775622479793, 1295.4688577683105, 1303.582853486894, 1308.188922867828, 815.0504662068623, 922.9538523073953, 924.4083786376724, 936.7751739582451, 946.129560804062, 5701.657900525982, 5814.098814683731, 5859.5019423349495, 5989.790357337472, 6042.969785151046, 379.4156998222761, 428.14157613984383, 454.6794509870533, 455.4602795687055, 459.5794847714371, 3927.626875446828, 3977.3835055566433, 3999.060220848098, 4042.6765858731233, 4052.7365721228025, 10401.323913945569, 10681.83977240342, 10805.973432523897, 10829.620963266249, 10848.837267440995, 10747.946260268649, 10762.623308184333, 10771.730706732395, 10795.730106747395, 10805.351678899291, 7701.730439434593, 8086.3935204861045, 8181.263686268265, 8278.006639203335, 8278.668853245803, 5926.4666180044005, 5997.521437566288, 6013.819585412444, 6030.276366836008, 6193.907091393723, 9260.583273752833, 10038.949805250926, 10078.0, 10082.899171008195, 10089.747756306151, 9959.302848800515, 10139.442330671589, 10184.949075254577, 10225.948870255601, 10459.21555883327, 7523.3229009389415, 7662.157162712208, 7743.651535680795, 7756.3019328260725, 7766.184550622329, 8295.336373090018, 8313.210245026712, 8342.958285208535, 8389.286910612665, 8398.87401688963, 7110.416905023521, 7357.5953322567775, 7487.138979017483, 7510.324070833652, 7572.507786993828, 7421.4062874968795, 7422.4433167513735, 7429.7399591014055, 7439.256074392518, 7553.584552849646, 6826.214985276757, 6827.419669328163, 6882.277360877036, 6897.827554311182, 6927.134108236471, 4849.660523763303, 4914.410270767531, 5003.224500202475, 5059.4315762113865, 5104.540591346648, 4928.901421971612, 5058.848234552969, 5086.143557601523, 5207.27098206259, 5212.843614691566], "value": 4502.767501787637, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [263.2733655111891, 296.22389339929384, 311.7973317343726, 319.6643524299486, 321.193803553082, 4032.790162951114, 4685.390899183106, 5311.893762124755, 5402.8919421611545, 5611.663300201991, 4252.957470425295, 4754.524547545244, 5193.16909294513, 5402.756875940582, 5418.83743487695, 3962.9009274768146, 4081.8979525511804, 4097.713160078795, 4163.5836416358425, 4169.852525949139, 3018.381231847466, 3502.562179727534, 4069.4302797608357, 4118.0, 4179.9791001045105, 3808.1812410331772, 3848.538175418954, 4195.538490766014, 4215.85244516442, 4237.4279472271155, 3319.319539494422, 3594.658507441789, 3842.8078596070086, 4220.915581688364, 4232.365145228225, 2856.871440785149, 3138.4350816852957, 3318.5685860837934, 3648.5804132524836, 3675.9339791460443, 4601.83893563727, 5321.680699158024, 5382.865428364283, 5385.461453854622, 5390.676559406448, 4501.977490112561, 4848.2727590861305, 4848.903021939542, 4854.854354369375, 4872.756362181876, 5808.896309701143, 5813.651180929158, 5846.590738648296, 5873.035700901917, 5946.672932988684, 3373.8650453981813, 3526.2066035142143, 4037.9596204037875, 4067.3898915162963, 4079.2045551117544, 5036.967421678547, 5507.752151153208, 5973.387185459945, 6010.639361638316, 6110.816675499697, 3696.5194524711615, 4080.18396320737, 4083.4691490106097, 4102.466679331667, 4105.97947010263, 3834.8687137294564, 4146.398772178018, 4161.500619925629, 4171.791410429466, 4192.727472714325, 4556.635469162459, 4834.20235661112, 4853.835079580946, 4870.805167793319, 4875.9512404875495, 4621.376114224604, 5048.469910659402, 5803.955288048108, 5827.271591051119, 5867.970660146672, 1248.9072061945797, 1289.3295486347047, 1347.0166778251735, 1355.9186448813102, 1442.776369662704, 800.72284705894, 820.8481430935296, 831.846108469935, 894.8478758610921, 897.3628723606182, 5163.638545301793, 5366.195070739421, 5397.4062853086125, 5404.216388623628, 5412.404635490093, 5305.65513241638, 5451.727413629382, 5458.563314934796, 5499.285092937893, 5993.160957465958, 3750.7937063461227, 4838.588719958843, 5372.811951581676, 5399.055165346049, 5509.256250406224, 3082.8458577071056, 3179.1734149121394, 3179.5230715392877, 3214.035789263212, 3247.253131779704, 5079.74601269935, 5901.704914754245, 6097.3292937776805, 6210.565260431817, 6271.717772700148, 5872.471477566954, 6125.387461253928, 6180.805813935988, 6204.193454850841, 6238.1266622672865, 5479.369872464637, 5828.1917418130215, 6106.297775755845, 6192.690365481708, 6281.497480201573, 5250.65870718402, 5784.797532086525, 5940.227770389822, 5981.66714981149, 6001.309849367379, 5424.728763561885, 5886.440093375345, 5921.940780592139, 5930.140129681258, 5932.525397968066, 5382.757775900016, 5870.9412905870395, 6249.468795152463, 6256.0, 6286.811395658137, 2943.646762388527, 3127.390158918989, 3135.623725152905, 3347.163209197701, 3464.4976478410995, 4349.369341445409, 4530.870490869286, 4871.075925009673, 5067.403767813127, 5130.050940576006, 4002.9799851000557, 4240.346264956608, 4328.273187891608, 4792.065547218259, 4997.450280469218, 4628.791704373379, 4847.3698419205275, 4934.333864928332, 4956.727379994135, 5021.0, 3949.0, 4140.254754144163, 4510.300903359985, 4677.625789936729, 4912.680675756064, 4991.700497970133, 5253.632245742761, 5511.22842802016, 5600.663960162403, 5785.884282314414, 5298.3508245877065, 5653.265075540154, 5669.461401166883, 5683.460071293221, 5702.403327068261], "value": 4070.232494901331, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.937305955934, 671.6943790575287, 683.8666460040292, 691.529759763361, 707.9858402831941, 12901.612951611454, 15133.243337833106, 16207.270672819723, 16236.675266494693, 16263.024218546896, 14647.0, 14707.338169782386, 14805.92597037016, 14862.925685371585, 14868.330925108368, 677.502036003537, 847.2882778466087, 849.452103393311, 870.6430363550945, 872.7905302727331, 852.2415050604935, 862.1163307609687, 863.9308855291594, 866.6100254885326, 869.4739682492096, 933.593886659302, 938.5119737736374, 940.1538615246264, 942.1850099663776, 947.999860147544, 910.9863352049724, 933.0529512544772, 934.1919239857508, 935.4434111703594, 940.4028441939383, 669.6919417068106, 676.1210426445601, 693.7051753004953, 696.3176087434342, 696.7874798186601, 15135.697286054326, 15144.621384465367, 15252.38990440381, 15258.160801155933, 15298.311575979107, 14633.634159146106, 14749.852501474956, 14804.777928331083, 14881.10713357202, 15074.24628768568, 11606.36165010924, 11647.59233426826, 11754.529818807237, 11762.58830940913, 11774.116941229442, 3771.396576547739, 3832.7891965941863, 3837.76973381598, 3989.5412027616617, 3991.720579559432, 110876.0, 111810.44094779473, 112777.30834037496, 112940.0, 117602.41198794037, 10967.725806854733, 11043.418823739177, 11072.778544429228, 11218.102551795837, 11356.602518911799, 23535.646965295535, 23750.168744093873, 23777.643335349985, 23785.643215351785, 23789.167379141993, 7961.562114083781, 7963.044434667877, 7993.800154996055, 7995.440319177718, 8130.268275855202, 4431.731275389085, 4456.732596044248, 4465.866024019285, 4498.0, 4499.415076040159, 3088.660247372787, 3587.8385472653795, 3666.1018050577777, 3667.4132138857653, 3723.478712980185, 512.4107276631903, 607.6931149769426, 607.7508221629199, 611.8466690288838, 611.9204503414528, 531.0918329656331, 554.8210053636024, 592.5896366647438, 666.6933210723025, 667.7912977510721, 9074.604385695438, 9361.0, 9407.952960235156, 9448.574814133242, 9466.763330916645, 515.8452464260782, 629.8992161254178, 663.575311800448, 665.1220389086457, 670.8356452669127, 4457.977710111429, 4572.565606267399, 4629.722216667011, 4641.535846415399, 4660.487346391894, 158930.0, 167603.0, 172344.0, 174231.0, 175203.0, 57329.713351432976, 76207.0, 78892.21107788963, 92158.0, 114217.86494553233, 66334.6733065336, 68155.97766033513, 81068.59465702633, 89738.55130724305, 117886.0, 7847.332976696816, 7916.071053710164, 9277.48973806447, 9877.802443950937, 9963.103320701171, 45644.77177614091, 57609.711951439975, 59684.70157649184, 61183.38816611955, 62528.74942501215, 75487.62256188683, 82815.0, 85218.57390713006, 87552.0, 88469.0, 9333.813323733351, 11069.83395249088, 11276.9436152822, 11454.484548195185, 12164.965977891978, 12825.871741282468, 14280.642983925485, 14744.705105897607, 15034.774478382615, 15239.923800380928, 12937.482500700244, 13324.276619796628, 13586.456541738615, 13933.651658708208, 14268.14391136535, 13314.800277996032, 13943.930280348533, 13971.0, 14159.575212743634, 14457.204853733145, 13398.531051413154, 13517.86482135166, 14039.929800350932, 14176.220307882753, 14206.715865682836, 11085.667429977113, 11130.387828669502, 11384.943075284571, 11392.544298227978, 11880.940595296968, 9931.602735890483, 10300.369639972685, 10381.325213861377, 10672.30630009047, 11418.477935452993], "value": 7652.388210344662, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1628.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 114.0, 116.0, 114.0, 116.0, 116.0, 116.0, 118.0, 118.0, 134.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 134.0, 176.0, 190.0, 114.0, 135.0, 140.0, 148.0, 170.0, 113.0], "value": 144.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 208.0, 215.0, 217.0, 198.0, 200.0, 204.0, 198.0, 197.0, 193.0], "value": 202.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 80.0, 76.0, 148.0, 82.0, 115.0, 82.0, 61.0, 142.0, 51.0], "value": 90.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 122.0, 70.0, 69.0, 75.0, 108.0, 64.0, 74.0, 110.0], "value": 83.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 90.0, 133.0, 132.0, 94.0, 75.0, 139.0, 131.0, 91.0, 86.0], "value": 107.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 118.0, 154.0, 108.0, 154.0, 102.0, 151.0], "value": 117.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 154.0, 93.0, 93.0, 111.0, 151.0, 115.0, 90.0, 90.0, 91.0], "value": 110.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 57.0, 59.0, 58.0, 58.0, 58.0, 57.0, 57.0, 57.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 102.0, 105.0, 110.0, 80.0, 75.0, 73.0, 74.0, 69.0, 71.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 90.0, 84.0, 85.0, 88.0, 98.0, 111.0, 83.0, 89.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 138.0, 136.0, 144.0, 142.0, 171.0, 144.0, 149.0, 171.0, 152.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 176.0, 241.0, 229.0, 173.0, 175.0, 247.0, 242.0, 170.0, 244.0], "value": 206.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 70.0, 67.0, 69.0, 70.0, 69.0, 70.0, 68.0, 67.0], "value": 69.0, "unit": "score"}]}]} 13:12:35 INFO - Return code: 0 13:12:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:12:35 ERROR - # TBPL SUCCESS # 13:12:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:12:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:12:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:12:35 INFO - 2016-09-04 13:12:35.437 screenresolution[1690:10631] starting screenresolution argv=screenresolution get 13:12:35 INFO - 2016-09-04 13:12:35.440 screenresolution[1690:10631] Display 0: 1600x1200x32@60 13:12:35 INFO - 2016-09-04 13:12:35.446 screenresolution[1691:10634] starting screenresolution argv=screenresolution list 13:12:35 INFO - Available Modes on Display 0 13:12:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:12:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:12:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:12:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:12:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:12:35 INFO - Intel Iris: 13:12:35 INFO - Chipset Model: Intel Iris 13:12:35 INFO - Type: GPU 13:12:35 INFO - Bus: Built-In 13:12:35 INFO - VRAM (Dynamic, Max): 1536 MB 13:12:35 INFO - Vendor: Intel (0x8086) 13:12:35 INFO - Device ID: 0x0a2e 13:12:35 INFO - Revision ID: 0x0009 13:12:35 INFO - Displays: 13:12:35 INFO - Display: 13:12:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:12:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:12:35 INFO - Display Serial Number: 1600x1200 60 13:12:35 INFO - Main Display: Yes 13:12:35 INFO - Mirror: Off 13:12:35 INFO - Online: Yes 13:12:35 INFO - Rotation: Supported 13:12:35 INFO - Return code: 0 13:12:35 INFO - Running post-action listener: _resource_record_post_action 13:12:35 INFO - [mozharness: 2016-09-04 20:12:35.680778Z] Finished run-tests step (success) 13:12:35 INFO - Running post-run listener: _resource_record_post_run 13:12:35 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38065152; Write bytes: 708654080; Read time: 368; Write time: 2005 13:12:35 INFO - TinderboxPrint: CPU usage
54.4% 13:12:35 INFO - TinderboxPrint: I/O read bytes / time
38,065,152 / 368 13:12:35 INFO - TinderboxPrint: I/O write bytes / time
708,654,080 / 2,005 13:12:35 INFO - TinderboxPrint: CPU idle
2,211.2 (45.6%) 13:12:35 INFO - TinderboxPrint: CPU system
977.3 (20.2%) 13:12:35 INFO - TinderboxPrint: CPU user
1,657.2 (34.2%) 13:12:35 INFO - TinderboxPrint: Swap in / out
457,379,840 / 0 13:12:35 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 399383040; Write bytes: 388904448; Read time: 29622; Write time: 725 13:12:35 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30972928; Write bytes: 298950144; Read time: 287; Write time: 1239 13:12:36 INFO - Running post-run listener: _upload_blobber_files 13:12:36 INFO - Blob upload gear active. 13:12:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:12:36 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 13:12:36 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'] 13:12:36 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 13:12:36 INFO - (blobuploader) - INFO - Open directory for files ... 13:12:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:12:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:12:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:12:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:12:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:12:37 INFO - (blobuploader) - INFO - Done attempting. 13:12:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:12:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:12:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:12:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:12:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:12:38 INFO - (blobuploader) - INFO - Done attempting. 13:12:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:12:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:12:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:12:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:12:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:12:40 INFO - (blobuploader) - INFO - Done attempting. 13:12:40 INFO - (blobuploader) - INFO - Iteration through files over. 13:12:40 INFO - Return code: 0 13:12:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:12:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:12:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc9faebb3434af13a47a231704b8a800011fa18a920202de5e9c73aaddaef45fa4ee002694e57ffdc7fdfd72b6dbbbaed82569b9a2107bc8b24abd6ef496ad26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70e0643be7ef0946aa5e4ff6fafef88c1ed894f6d14aa2ab6c612120ebf567e9bdc7acc0b4ef42032cb4c70c3a321bb55df3d19466cdbf6d61dd15361e4dbdaa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/593433f920471dcc9fd0437f1c84205d2b42c75bb837bc3b25abeebcf49c3fdba13e61da17628bc8f3056ec8e12157f84459a9625a74ca3ccded7ef03d01b445"} 13:12:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:12:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:12:40 INFO - Contents: 13:12:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc9faebb3434af13a47a231704b8a800011fa18a920202de5e9c73aaddaef45fa4ee002694e57ffdc7fdfd72b6dbbbaed82569b9a2107bc8b24abd6ef496ad26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70e0643be7ef0946aa5e4ff6fafef88c1ed894f6d14aa2ab6c612120ebf567e9bdc7acc0b4ef42032cb4c70c3a321bb55df3d19466cdbf6d61dd15361e4dbdaa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/593433f920471dcc9fd0437f1c84205d2b42c75bb837bc3b25abeebcf49c3fdba13e61da17628bc8f3056ec8e12157f84459a9625a74ca3ccded7ef03d01b445"} 13:12:40 INFO - Running post-run listener: copy_logs_to_upload_dir 13:12:40 INFO - Copying logs to upload dir... 13:12:40 INFO - mkdir: /builds/slave/test/build/upload/logs 13:12:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.254643 ========= master_lag: 0.51 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-04 13:12:41.263822) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-04 13:12:41.267010) ========= 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.zhqEt3l95f/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.XQFAozgsU1/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc9faebb3434af13a47a231704b8a800011fa18a920202de5e9c73aaddaef45fa4ee002694e57ffdc7fdfd72b6dbbbaed82569b9a2107bc8b24abd6ef496ad26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70e0643be7ef0946aa5e4ff6fafef88c1ed894f6d14aa2ab6c612120ebf567e9bdc7acc0b4ef42032cb4c70c3a321bb55df3d19466cdbf6d61dd15361e4dbdaa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/593433f920471dcc9fd0437f1c84205d2b42c75bb837bc3b25abeebcf49c3fdba13e61da17628bc8f3056ec8e12157f84459a9625a74ca3ccded7ef03d01b445"} build_url:https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011082 build_url: 'https://queue.taskcluster.net/v1/task/awPvStcnTKm3yQE0V5r9yA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc9faebb3434af13a47a231704b8a800011fa18a920202de5e9c73aaddaef45fa4ee002694e57ffdc7fdfd72b6dbbbaed82569b9a2107bc8b24abd6ef496ad26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70e0643be7ef0946aa5e4ff6fafef88c1ed894f6d14aa2ab6c612120ebf567e9bdc7acc0b4ef42032cb4c70c3a321bb55df3d19466cdbf6d61dd15361e4dbdaa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/593433f920471dcc9fd0437f1c84205d2b42c75bb837bc3b25abeebcf49c3fdba13e61da17628bc8f3056ec8e12157f84459a9625a74ca3ccded7ef03d01b445"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-04 13:12:41.300441) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 13:12:41.300772) ========= 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.zhqEt3l95f/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.XQFAozgsU1/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.004930 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 13:12:41.349434) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-04 13:12:41.349759) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-04 13:12:41.350075) ========= ========= Total master_lag: 0.65 =========