builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0340 starttime: 1473718120.06 results: success (0) buildid: 20160912131516 builduid: 1f0eb0d3f0a34a31b957ce95904b42de revision: 494bab58654bc4fa444742016ee4f947e093be27 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 15:08:40.059571) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 15:08:40.060021) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 15:08:40.060309) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 15:08:40.089820) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 15:08:40.090173) ========= 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.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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-12 15:08:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 154M=0s 2016-09-12 15:08:40 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092647 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 15:08:40.205732) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 15:08:40.206234) ========= 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.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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.027426 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 15:08:40.255499) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 15:08:40.255849) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 494bab58654bc4fa444742016ee4f947e093be27 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 494bab58654bc4fa444742016ee4f947e093be27 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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-12 15:08:40,343 truncating revision to first 12 chars 2016-09-12 15:08:40,343 Setting DEBUG logging. 2016-09-12 15:08:40,343 attempt 1/10 2016-09-12 15:08:40,343 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/494bab58654b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 15:08:41,496 unpacking tar archive at: autoland-494bab58654b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.418057 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 15:08:41.696359) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 15:08:41.696706) ========= script_repo_revision: 494bab58654bc4fa444742016ee4f947e093be27 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 15:08:41.697092) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 15:08:41.697386) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 15:08:41.716077) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-09-12 15:08:41.716403) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 15:08:41 INFO - MultiFileLogger online at 20160912 15:08:41 in /builds/slave/test 15:08:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 15:08:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:08:41 INFO - {'append_to_log': False, 15:08:41 INFO - 'base_work_dir': '/builds/slave/test', 15:08:41 INFO - 'blob_upload_branch': 'Autoland', 15:08:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:08:41 INFO - 'branch': 'Autoland', 15:08:41 INFO - 'buildbot_json_path': 'buildprops.json', 15:08:41 INFO - 'config_files': ('talos/mac_config.py',), 15:08:41 INFO - 'default_actions': ('clobber', 15:08:41 INFO - 'read-buildbot-config', 15:08:41 INFO - 'download-and-extract', 15:08:41 INFO - 'populate-webroot', 15:08:41 INFO - 'create-virtualenv', 15:08:41 INFO - 'install', 15:08:41 INFO - 'run-tests'), 15:08:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:08:41 INFO - 'download_minidump_stackwalk': True, 15:08:41 INFO - 'download_symbols': 'ondemand', 15:08:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:08:41 INFO - 'tooltool.py': '/tools/tooltool.py', 15:08:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:08:41 INFO - '/tools/misc-python/virtualenv.py')}, 15:08:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:08:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:08:41 INFO - 'installer_path': 'installer.exe', 15:08:41 INFO - 'log_level': 'info', 15:08:41 INFO - 'log_name': 'talos', 15:08:41 INFO - 'log_to_console': True, 15:08:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:08:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:08:41 INFO - 'opt_config_files': (), 15:08:41 INFO - 'pip_index': False, 15:08:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:08:41 INFO - 'cmd': ('bash', 15:08:41 INFO - '-c', 15:08:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:08:41 INFO - 'enabled': True, 15:08:41 INFO - 'halt_on_failure': False, 15:08:41 INFO - 'name': 'check_screen_resolution'},), 15:08:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:08:41 INFO - 'cmd': ('bash', 15:08:41 INFO - '-c', 15:08:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:08:41 INFO - 'enabled': True, 15:08:41 INFO - 'halt_on_failure': False, 15:08:41 INFO - 'name': 'check_screen_resolution'},), 15:08:41 INFO - 'run_cmd_checks_enabled': True, 15:08:41 INFO - 'sps_profile': False, 15:08:41 INFO - 'sps_profile_interval': 0, 15:08:41 INFO - 'suite': 'dromaeojs-e10s', 15:08:41 INFO - 'system_bits': '32', 15:08:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:08:41 INFO - 'title': 't-yosemite-r7-0340', 15:08:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:08:41 INFO - 'use_talos_json': True, 15:08:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:08:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:08:41 INFO - 'work_dir': 'build'} 15:08:41 INFO - [mozharness: 2016-09-12 22:08:41.921112Z] Running clobber step. 15:08:41 INFO - Running pre-action listener: _resource_record_pre_action 15:08:41 INFO - Running main action method: clobber 15:08:41 INFO - rmtree: /builds/slave/test/build 15:08:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:08:44 INFO - Running post-action listener: _resource_record_post_action 15:08:44 INFO - [mozharness: 2016-09-12 22:08:44.404026Z] Finished clobber step (success) 15:08:44 INFO - [mozharness: 2016-09-12 22:08:44.404160Z] Running read-buildbot-config step. 15:08:44 INFO - Running pre-action listener: _resource_record_pre_action 15:08:44 INFO - Running main action method: read_buildbot_config 15:08:44 INFO - Using buildbot properties: 15:08:44 INFO - { 15:08:44 INFO - "project": "", 15:08:44 INFO - "product": "firefox", 15:08:44 INFO - "script_repo_revision": "production", 15:08:44 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 15:08:44 INFO - "repository": "", 15:08:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 15:08:44 INFO - "buildid": "20160912131516", 15:08:44 INFO - "pgo_build": "False", 15:08:44 INFO - "basedir": "/builds/slave/test", 15:08:44 INFO - "buildnumber": 169, 15:08:44 INFO - "slavename": "t-yosemite-r7-0340", 15:08:44 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:08:44 INFO - "platform": "yosemite_r7", 15:08:44 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 15:08:44 INFO - "branch": "autoland", 15:08:44 INFO - "repo_path": "integration/autoland", 15:08:44 INFO - "revision": "494bab58654bc4fa444742016ee4f947e093be27", 15:08:44 INFO - "stage_platform": "macosx64", 15:08:44 INFO - "builduid": "1f0eb0d3f0a34a31b957ce95904b42de", 15:08:44 INFO - "slavebuilddir": "test" 15:08:44 INFO - } 15:08:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:08:44 INFO - Running post-action listener: _resource_record_post_action 15:08:44 INFO - [mozharness: 2016-09-12 22:08:44.407628Z] Finished read-buildbot-config step (success) 15:08:44 INFO - [mozharness: 2016-09-12 22:08:44.407763Z] Running download-and-extract step. 15:08:44 INFO - Running pre-action listener: _resource_record_pre_action 15:08:44 INFO - Running main action method: download_and_extract 15:08:44 INFO - mkdir: /builds/slave/test/build/tests 15:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:08:44 INFO - trying https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:08:44 INFO - Downloading https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/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 15:08:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/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 15:08:45 INFO - Downloaded 1199 bytes. 15:08:45 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:08:45 INFO - Using the following test package requirements: 15:08:45 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:08:45 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:45 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:08:45 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:45 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:08:45 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:45 INFO - u'jsshell-mac.zip'], 15:08:45 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:45 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:08:45 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:08:45 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:45 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:08:45 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:45 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:08:45 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:45 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:08:45 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:45 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:08:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:08:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:08:46 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:08:53 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 15:08:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:08:53 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:09:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:09:01 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:09:01 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')]} 15:09:01 INFO - trying https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:09:01 INFO - Downloading https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:09:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/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 15:09:04 INFO - Downloaded 111656978 bytes. 15:09:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:09:04 INFO - mkdir: /builds/slave/test/properties 15:09:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:09:04 INFO - Writing to file /builds/slave/test/properties/build_url 15:09:04 INFO - Contents: 15:09:04 INFO - build_url:https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:09:05 INFO - Running post-action listener: _resource_record_post_action 15:09:05 INFO - Running post-action listener: set_extra_try_arguments 15:09:05 INFO - [mozharness: 2016-09-12 22:09:05.429425Z] Finished download-and-extract step (success) 15:09:05 INFO - [mozharness: 2016-09-12 22:09:05.429559Z] Running populate-webroot step. 15:09:05 INFO - Running pre-action listener: _resource_record_pre_action 15:09:05 INFO - Running main action method: populate_webroot 15:09:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:09:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:09:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:09:05 INFO - '--tppagecycles', 15:09:05 INFO - '7'], 15:09:05 INFO - 'tests': ['tsvgm']}}, 15:09:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:09:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:09:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:09:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:09:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:09:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:09:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:05 INFO - 'talos_options': ['--disable-e10s'], 15:09:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:09:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:09:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:05 INFO - 'talos_options': ['--disable-e10s'], 15:09:05 INFO - 'tests': ['damp', 'tps']}, 15:09:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:05 INFO - 'tests': ['damp', 'tps']}, 15:09:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:09:05 INFO - 'tests': ['dromaeo_dom']}, 15:09:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:09:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:09:05 INFO - 'tests': ['basic_compositor_video']}, 15:09:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:09:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:09:05 INFO - 'tests': ['a11yr', 15:09:05 INFO - 'ts_paint', 15:09:05 INFO - 'tpaint', 15:09:05 INFO - 'sessionrestore', 15:09:05 INFO - 'sessionrestore_no_auto_restore', 15:09:05 INFO - 'tabpaint']}, 15:09:05 INFO - 'other-e10s': {'tests': ['a11yr', 15:09:05 INFO - 'ts_paint', 15:09:05 INFO - 'tpaint', 15:09:05 INFO - 'sessionrestore', 15:09:05 INFO - 'sessionrestore_no_auto_restore', 15:09:05 INFO - 'tabpaint']}, 15:09:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:09:05 INFO - 'ts_paint', 15:09:05 INFO - 'tpaint', 15:09:05 INFO - 'sessionrestore', 15:09:05 INFO - 'sessionrestore_no_auto_restore', 15:09:05 INFO - 'tabpaint']}, 15:09:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:09:05 INFO - 'ts_paint', 15:09:05 INFO - 'tpaint', 15:09:05 INFO - 'sessionrestore', 15:09:05 INFO - 'sessionrestore_no_auto_restore', 15:09:05 INFO - 'tabpaint']}, 15:09:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:09:05 INFO - 'tests': ['a11yr', 15:09:05 INFO - 'ts_paint', 15:09:05 INFO - 'tpaint', 15:09:05 INFO - 'sessionrestore', 15:09:05 INFO - 'sessionrestore_no_auto_restore', 15:09:05 INFO - 'tabpaint']}, 15:09:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:09:05 INFO - 'tests': ['a11yr', 15:09:05 INFO - 'ts_paint', 15:09:05 INFO - 'tpaint', 15:09:05 INFO - 'sessionrestore', 15:09:05 INFO - 'sessionrestore_no_auto_restore', 15:09:05 INFO - 'tabpaint']}, 15:09:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:09:05 INFO - 'tests': ['tsvgx', 15:09:05 INFO - 'tsvgr_opacity', 15:09:05 INFO - 'tart', 15:09:05 INFO - 'tscrollx', 15:09:05 INFO - 'cart']}, 15:09:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:09:05 INFO - 'tsvgr_opacity', 15:09:05 INFO - 'tart', 15:09:05 INFO - 'tscrollx', 15:09:05 INFO - 'cart']}, 15:09:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:05 INFO - 'talos_options': ['--disable-e10s'], 15:09:05 INFO - 'tests': ['tp5o']}, 15:09:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:05 INFO - 'tests': ['tp5o']}, 15:09:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:05 INFO - 'talos_options': ['--disable-e10s', 15:09:05 INFO - '--xperf_path', 15:09:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:09:05 INFO - 'tests': ['tp5n']}, 15:09:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:05 INFO - 'talos_options': ['--xperf_path', 15:09:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:09:05 INFO - 'tests': ['tp5n']}}, 15:09:05 INFO - 'talos.zip': {'path': '', 15:09:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:09:05 INFO - Running post-action listener: _resource_record_post_action 15:09:05 INFO - [mozharness: 2016-09-12 22:09:05.448589Z] Finished populate-webroot step (success) 15:09:05 INFO - [mozharness: 2016-09-12 22:09:05.448708Z] Running create-virtualenv step. 15:09:05 INFO - Running pre-action listener: _resource_record_pre_action 15:09:05 INFO - Running main action method: create_virtualenv 15:09:05 INFO - Creating virtualenv /builds/slave/test/build/venv 15:09:05 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 15:09:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:09:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:09:05 INFO - Using real prefix '/tools/python27' 15:09:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:09:06 INFO - Installing distribute.............................................................................................................................................................................................done. 15:09:09 INFO - Installing pip.................done. 15:09:09 INFO - Return code: 0 15:09:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:09:09 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')]} 15:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:09 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')]} 15:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:09 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 0x10acda490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac50570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68a5726f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaeed30>, '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 0x10a914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aae6b20>, '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.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 15:09:09 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 15:09:09 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 15:09:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cWPlHB7Qb6/Render', 15:09:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:09 INFO - 'HOME': '/Users/cltbld', 15:09:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:09 INFO - 'LOGNAME': 'cltbld', 15:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:09 INFO - 'MOZ_NO_REMOTE': '1', 15:09:09 INFO - 'NO_EM_RESTART': '1', 15:09:09 INFO - 'PAGER': '/bin/cat', 15:09:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:09 INFO - 'PWD': '/builds/slave/test', 15:09:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:09 INFO - 'SHELL': '/bin/bash', 15:09:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0wYuLKZiS/Listeners', 15:09:09 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 15:09:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:09 INFO - 'USER': 'cltbld', 15:09:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:09 INFO - 'XPC_FLAGS': '0x0', 15:09:09 INFO - 'XPC_SERVICE_NAME': '0', 15:09:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:09 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 15:09:09 INFO - Cleaning up... 15:09:09 INFO - Return code: 0 15:09:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:09:09 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')]} 15:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:09 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')]} 15:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:09 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 0x10acda490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac50570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68a5726f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaeed30>, '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 0x10a914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aae6b20>, '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.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 15:09:09 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 15:09:09 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 15:09:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cWPlHB7Qb6/Render', 15:09:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:09 INFO - 'HOME': '/Users/cltbld', 15:09:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:09 INFO - 'LOGNAME': 'cltbld', 15:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:09 INFO - 'MOZ_NO_REMOTE': '1', 15:09:09 INFO - 'NO_EM_RESTART': '1', 15:09:09 INFO - 'PAGER': '/bin/cat', 15:09:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:09 INFO - 'PWD': '/builds/slave/test', 15:09:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:09 INFO - 'SHELL': '/bin/bash', 15:09:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0wYuLKZiS/Listeners', 15:09:09 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 15:09:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:09 INFO - 'USER': 'cltbld', 15:09:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:09 INFO - 'XPC_FLAGS': '0x0', 15:09:09 INFO - 'XPC_SERVICE_NAME': '0', 15:09:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:09 INFO - Downloading/unpacking psutil>=3.1.1 15:09:09 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 15:09:09 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 15:09:09 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 15:09:09 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 15:09:09 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 15:09:09 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 15:09:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:09:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:09:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:09:12 INFO - Installing collected packages: psutil 15:09:12 INFO - Running setup.py install for psutil 15:09:12 INFO - building 'psutil._psutil_osx' extension 15:09:12 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 15:09:13 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 15:09:13 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 15:09:13 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 15:09:13 INFO - building 'psutil._psutil_posix' extension 15:09:13 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 15:09:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:09:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:09:13 INFO - ^ 15:09:13 INFO - 1 warning generated. 15:09:13 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 15:09:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:09:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:09:13 INFO - Successfully installed psutil 15:09:13 INFO - Cleaning up... 15:09:13 INFO - Return code: 0 15:09:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10acda490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac50570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68a5726f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaeed30>, '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 0x10a914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aae6b20>, '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.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 15:09:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:09:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:09:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cWPlHB7Qb6/Render', 15:09:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:13 INFO - 'HOME': '/Users/cltbld', 15:09:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:13 INFO - 'LOGNAME': 'cltbld', 15:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:13 INFO - 'MOZ_NO_REMOTE': '1', 15:09:13 INFO - 'NO_EM_RESTART': '1', 15:09:13 INFO - 'PAGER': '/bin/cat', 15:09:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:13 INFO - 'PWD': '/builds/slave/test', 15:09:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:13 INFO - 'SHELL': '/bin/bash', 15:09:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0wYuLKZiS/Listeners', 15:09:13 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 15:09:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:13 INFO - 'USER': 'cltbld', 15:09:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:13 INFO - 'XPC_FLAGS': '0x0', 15:09:13 INFO - 'XPC_SERVICE_NAME': '0', 15:09:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:09:14 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 15:09:14 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 15:09:14 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 15:09:14 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 15:09:14 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 15:09:14 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 15:09:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:09:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:09:16 INFO - Installing collected packages: mozsystemmonitor 15:09:16 INFO - Running setup.py install for mozsystemmonitor 15:09:16 INFO - Successfully installed mozsystemmonitor 15:09:16 INFO - Cleaning up... 15:09:16 INFO - Return code: 0 15:09:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:16 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 0x10acda490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac50570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68a5726f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaeed30>, '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 0x10a914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aae6b20>, '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.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 15:09:16 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 15:09:16 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 15:09:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cWPlHB7Qb6/Render', 15:09:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:16 INFO - 'HOME': '/Users/cltbld', 15:09:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:16 INFO - 'LOGNAME': 'cltbld', 15:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:16 INFO - 'MOZ_NO_REMOTE': '1', 15:09:16 INFO - 'NO_EM_RESTART': '1', 15:09:16 INFO - 'PAGER': '/bin/cat', 15:09:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:16 INFO - 'PWD': '/builds/slave/test', 15:09:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:16 INFO - 'SHELL': '/bin/bash', 15:09:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0wYuLKZiS/Listeners', 15:09:16 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 15:09:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:16 INFO - 'USER': 'cltbld', 15:09:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:16 INFO - 'XPC_FLAGS': '0x0', 15:09:16 INFO - 'XPC_SERVICE_NAME': '0', 15:09:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:17 INFO - Downloading/unpacking jsonschema==2.5.1 15:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:09:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:09: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 15:09: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 15:09: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 15:09: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 15:09: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 15:09: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 15:09:20 INFO - Downloading functools32-3.2.3-2.tar.gz 15:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:09:20 INFO - warning: no files found matching '*.txt' 15:09:20 INFO - no previously-included directories found matching 'build' 15:09:20 INFO - no previously-included directories found matching 'dist' 15:09:20 INFO - no previously-included directories found matching '.git*' 15:09:20 INFO - Installing collected packages: jsonschema, functools32 15:09:20 INFO - Running setup.py install for functools32 15:09:20 INFO - warning: no files found matching '*.txt' 15:09:20 INFO - no previously-included directories found matching 'build' 15:09:20 INFO - no previously-included directories found matching 'dist' 15:09:20 INFO - no previously-included directories found matching '.git*' 15:09:20 INFO - Successfully installed jsonschema functools32 15:09:20 INFO - Cleaning up... 15:09:20 INFO - Return code: 0 15:09:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10acda490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac50570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68a5726f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaeed30>, '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 0x10a914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aae6b20>, '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.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 15:09:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:09:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cWPlHB7Qb6/Render', 15:09:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:20 INFO - 'HOME': '/Users/cltbld', 15:09:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:20 INFO - 'LOGNAME': 'cltbld', 15:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:20 INFO - 'MOZ_NO_REMOTE': '1', 15:09:20 INFO - 'NO_EM_RESTART': '1', 15:09:20 INFO - 'PAGER': '/bin/cat', 15:09:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:20 INFO - 'PWD': '/builds/slave/test', 15:09:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:20 INFO - 'SHELL': '/bin/bash', 15:09:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0wYuLKZiS/Listeners', 15:09:20 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 15:09:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:20 INFO - 'USER': 'cltbld', 15:09:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:20 INFO - 'XPC_FLAGS': '0x0', 15:09:20 INFO - 'XPC_SERVICE_NAME': '0', 15:09:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:09:20 INFO - Cleaning up... 15:09:20 INFO - Return code: 0 15:09:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10acda490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac50570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68a5726f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaeed30>, '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 0x10a914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aae6b20>, '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.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 15:09:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:09:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cWPlHB7Qb6/Render', 15:09:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:20 INFO - 'HOME': '/Users/cltbld', 15:09:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:20 INFO - 'LOGNAME': 'cltbld', 15:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:20 INFO - 'MOZ_NO_REMOTE': '1', 15:09:20 INFO - 'NO_EM_RESTART': '1', 15:09:20 INFO - 'PAGER': '/bin/cat', 15:09:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:20 INFO - 'PWD': '/builds/slave/test', 15:09:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:20 INFO - 'SHELL': '/bin/bash', 15:09:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0wYuLKZiS/Listeners', 15:09:20 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 15:09:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:20 INFO - 'USER': 'cltbld', 15:09:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:20 INFO - 'XPC_FLAGS': '0x0', 15:09:20 INFO - 'XPC_SERVICE_NAME': '0', 15:09:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:21 INFO - Downloading/unpacking blobuploader==1.2.4 15:09:21 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 15:09:21 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 15:09:21 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 15:09:21 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 15:09:21 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 15:09:21 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 15:09:23 INFO - Downloading blobuploader-1.2.4.tar.gz 15:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:09:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:09:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:24 INFO - Downloading docopt-0.6.1.tar.gz 15:09:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:09:24 INFO - Installing collected packages: blobuploader, requests, docopt 15:09:24 INFO - Running setup.py install for blobuploader 15:09:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:09:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:09:24 INFO - Running setup.py install for requests 15:09:25 INFO - Running setup.py install for docopt 15:09:25 INFO - Successfully installed blobuploader requests docopt 15:09:25 INFO - Cleaning up... 15:09:25 INFO - Return code: 0 15:09:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:25 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 0x10acda490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac50570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68a5726f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaeed30>, '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 0x10a914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aae6b20>, '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.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 15:09:25 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 15:09:25 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 15:09:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cWPlHB7Qb6/Render', 15:09:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:25 INFO - 'HOME': '/Users/cltbld', 15:09:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:25 INFO - 'LOGNAME': 'cltbld', 15:09:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:25 INFO - 'MOZ_NO_REMOTE': '1', 15:09:25 INFO - 'NO_EM_RESTART': '1', 15:09:25 INFO - 'PAGER': '/bin/cat', 15:09:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:25 INFO - 'PWD': '/builds/slave/test', 15:09:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:25 INFO - 'SHELL': '/bin/bash', 15:09:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0wYuLKZiS/Listeners', 15:09:25 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 15:09:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:25 INFO - 'USER': 'cltbld', 15:09:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:25 INFO - 'XPC_FLAGS': '0x0', 15:09:25 INFO - 'XPC_SERVICE_NAME': '0', 15:09:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:09:25 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-HqbMF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:09:26 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-QcHzBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:09:26 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-pY3pXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:09:26 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-CLyFOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:09:26 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-SXSRuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:09:26 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-GZVmHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:09:26 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-9H3az7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:09:26 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-t0i1f5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:09:26 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-b18Wol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:09:26 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-4SEIVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:09:27 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-SBQFZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:09:27 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-QXLwrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:09:27 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-oPOqQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:09:27 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-tCRC2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:27 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-Y_hWJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:09:27 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-AIgppB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:09:27 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-5kbxi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:09:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:09:27 INFO - Running setup.py install for manifestparser 15:09:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:09:27 INFO - Running setup.py install for mozcrash 15:09:28 INFO - Running setup.py install for mozdebug 15:09:28 INFO - Running setup.py install for mozdevice 15:09:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:09:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:09:28 INFO - Running setup.py install for mozfile 15:09:28 INFO - Running setup.py install for mozhttpd 15:09:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:09:28 INFO - Running setup.py install for mozinfo 15:09:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:09:28 INFO - Running setup.py install for mozInstall 15:09:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:09:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:09:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:09:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:09:28 INFO - Running setup.py install for mozleak 15:09:29 INFO - Running setup.py install for mozlog 15:09:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:09:29 INFO - Running setup.py install for moznetwork 15:09:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:09:29 INFO - Running setup.py install for mozprocess 15:09:29 INFO - Running setup.py install for mozprofile 15:09:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:09:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:09:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:09:29 INFO - Running setup.py install for mozrunner 15:09:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:09:29 INFO - Running setup.py install for mozscreenshot 15:09:30 INFO - Running setup.py install for moztest 15:09:30 INFO - Running setup.py install for mozversion 15:09:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:09:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:09:30 INFO - Cleaning up... 15:09:30 INFO - Return code: 0 15:09:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:09: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')]} 15:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09: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')]} 15:09:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:30 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 0x10acda490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac50570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68a5726f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaeed30>, '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 0x10a914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aae6b20>, '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.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 15:09:30 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 15:09:30 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 15:09:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cWPlHB7Qb6/Render', 15:09:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:30 INFO - 'HOME': '/Users/cltbld', 15:09:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:30 INFO - 'LOGNAME': 'cltbld', 15:09:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:30 INFO - 'MOZ_NO_REMOTE': '1', 15:09:30 INFO - 'NO_EM_RESTART': '1', 15:09:30 INFO - 'PAGER': '/bin/cat', 15:09:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:30 INFO - 'PWD': '/builds/slave/test', 15:09:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:30 INFO - 'SHELL': '/bin/bash', 15:09:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0wYuLKZiS/Listeners', 15:09:30 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 15:09:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:30 INFO - 'USER': 'cltbld', 15:09:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:30 INFO - 'XPC_FLAGS': '0x0', 15:09:30 INFO - 'XPC_SERVICE_NAME': '0', 15:09:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:09:30 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-DhHgEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:09:30 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)) 15:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:09:30 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-JzZ7g6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:09:30 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)) 15:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:09:30 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-M8PuHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:09:31 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)) 15:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:09:31 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-FAukJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:09:31 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)) 15:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:09:31 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-rVASHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:09:31 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)) 15:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:09:31 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-u0C0R9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:09:31 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)) 15:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:09:31 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-B4VQfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:09:31 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)) 15:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:09:31 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-3D48aZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:09:31 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)) 15:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:09:31 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-2_Obu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:09:31 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)) 15:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:09:31 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-OrPbJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:09:31 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)) 15:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:09:31 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-bZpTzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:09:31 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)) 15:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:09:31 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-GksbOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:09:31 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)) 15:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:09:31 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-gBfYnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:09:32 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)) 15:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:09:32 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-cEXdLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:09:32 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)) 15:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:32 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-zKA0U3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:32 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)) 15:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:09:32 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-qAsSOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:09:32 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)) 15:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:09:32 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-6U5Faw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:09:32 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)) 15:09:32 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)) 15:09:32 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)) 15:09:32 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)) 15:09:32 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)) 15:09:32 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)) 15:09:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:34 INFO - Downloading blessings-1.6.tar.gz 15:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:09:35 INFO - Installing collected packages: blessings 15:09:35 INFO - Running setup.py install for blessings 15:09:35 INFO - Successfully installed blessings 15:09:35 INFO - Cleaning up... 15:09:35 INFO - Return code: 0 15:09:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:09:35 INFO - Reading from file tmpfile_stdout 15:09:35 INFO - Current package versions: 15:09:35 INFO - blessings == 1.6 15:09:35 INFO - blobuploader == 1.2.4 15:09:35 INFO - docopt == 0.6.1 15:09:35 INFO - functools32 == 3.2.3-2 15:09:35 INFO - jsonschema == 2.5.1 15:09:35 INFO - manifestparser == 1.1 15:09:35 INFO - mozInstall == 1.12 15:09:35 INFO - mozcrash == 0.17 15:09:35 INFO - mozdebug == 0.1 15:09:35 INFO - mozdevice == 0.48 15:09:35 INFO - mozfile == 1.2 15:09:35 INFO - mozhttpd == 0.7 15:09:35 INFO - mozinfo == 0.9 15:09:35 INFO - mozleak == 0.1 15:09:35 INFO - mozlog == 3.3 15:09:35 INFO - moznetwork == 0.27 15:09:35 INFO - mozprocess == 0.23 15:09:35 INFO - mozprofile == 0.28 15:09:35 INFO - mozrunner == 6.12 15:09:35 INFO - mozscreenshot == 0.1 15:09:35 INFO - mozsystemmonitor == 0.3 15:09:35 INFO - moztest == 0.7 15:09:35 INFO - mozversion == 1.4 15:09:35 INFO - psutil == 3.1.1 15:09:35 INFO - requests == 1.2.3 15:09:35 INFO - wsgiref == 0.1.2 15:09:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:35 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 0x10acda490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac50570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68a5726f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaeed30>, '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 0x10a914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aae6b20>, '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.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 15:09:35 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 15:09:35 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 15:09:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cWPlHB7Qb6/Render', 15:09:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:35 INFO - 'HOME': '/Users/cltbld', 15:09:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:35 INFO - 'LOGNAME': 'cltbld', 15:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:35 INFO - 'MOZ_NO_REMOTE': '1', 15:09:35 INFO - 'NO_EM_RESTART': '1', 15:09:35 INFO - 'PAGER': '/bin/cat', 15:09:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:35 INFO - 'PWD': '/builds/slave/test', 15:09:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:35 INFO - 'SHELL': '/bin/bash', 15:09:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0wYuLKZiS/Listeners', 15:09:35 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 15:09:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:35 INFO - 'USER': 'cltbld', 15:09:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:35 INFO - 'XPC_FLAGS': '0x0', 15:09:35 INFO - 'XPC_SERVICE_NAME': '0', 15:09:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:35 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)) 15:09:35 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)) 15:09:35 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)) 15:09:35 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)) 15:09:35 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)) 15:09:35 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)) 15:09:35 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)) 15:09:35 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)) 15:09:35 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)) 15:09:35 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)) 15:09:35 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)) 15:09:35 INFO - Cleaning up... 15:09:35 INFO - Return code: 0 15:09:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10acda490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac50570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68a5726f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaeed30>, '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 0x10a914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aae6b20>, '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.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 15:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:09:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cWPlHB7Qb6/Render', 15:09:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:35 INFO - 'HOME': '/Users/cltbld', 15:09:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:35 INFO - 'LOGNAME': 'cltbld', 15:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:35 INFO - 'MOZ_NO_REMOTE': '1', 15:09:35 INFO - 'NO_EM_RESTART': '1', 15:09:35 INFO - 'PAGER': '/bin/cat', 15:09:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:35 INFO - 'PWD': '/builds/slave/test', 15:09:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:35 INFO - 'SHELL': '/bin/bash', 15:09:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0wYuLKZiS/Listeners', 15:09:35 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 15:09:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:35 INFO - 'USER': 'cltbld', 15:09:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:35 INFO - 'XPC_FLAGS': '0x0', 15:09:35 INFO - 'XPC_SERVICE_NAME': '0', 15:09:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:09:36 INFO - Cleaning up... 15:09:36 INFO - Return code: 0 15:09:36 INFO - Running post-action listener: _resource_record_post_action 15:09:36 INFO - Running post-action listener: _start_resource_monitoring 15:09:36 INFO - Starting resource monitoring. 15:09:36 INFO - [mozharness: 2016-09-12 22:09:36.139644Z] Finished create-virtualenv step (success) 15:09:36 INFO - [mozharness: 2016-09-12 22:09:36.140682Z] Running install step. 15:09:36 INFO - Running pre-action listener: _resource_record_pre_action 15:09:36 INFO - Running main action method: install 15:09:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:09:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:09:36 INFO - Reading from file tmpfile_stdout 15:09:36 INFO - Detecting whether we're running mozinstall >=1.0... 15:09:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:09:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:09:36 INFO - Reading from file tmpfile_stdout 15:09:36 INFO - Output received: 15:09:36 INFO - Usage: mozinstall [options] installer 15:09:36 INFO - Options: 15:09:36 INFO - -h, --help show this help message and exit 15:09:36 INFO - -d DEST, --destination=DEST 15:09:36 INFO - Directory to install application into. [default: 15:09:36 INFO - "/builds/slave/test"] 15:09:36 INFO - --app=APP Application being installed. [default: firefox] 15:09:36 INFO - mkdir: /builds/slave/test/build/application 15:09:36 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'] 15:09:36 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 15:10:11 INFO - Reading from file tmpfile_stdout 15:10:11 INFO - Output received: 15:10:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:10:11 INFO - Running post-action listener: _resource_record_post_action 15:10:11 INFO - [mozharness: 2016-09-12 22:10:11.005655Z] Finished install step (success) 15:10:11 INFO - [mozharness: 2016-09-12 22:10:11.005816Z] Running run-tests step. 15:10:11 INFO - Running pre-action listener: _resource_record_pre_action 15:10:11 INFO - Running main action method: run_tests 15:10:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:10:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:10:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:10:11 INFO - 2016-09-12 15:10:11.020 screenresolution[1648:7557] starting screenresolution argv=screenresolution get 15:10:11 INFO - 2016-09-12 15:10:11.022 screenresolution[1648:7557] Display 0: 1600x1200x32@60 15:10:11 INFO - 2016-09-12 15:10:11.028 screenresolution[1649:7559] starting screenresolution argv=screenresolution list 15:10:11 INFO - Available Modes on Display 0 15:10:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:10:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:10:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:10:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:10:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:10:11 INFO - Intel Iris: 15:10:11 INFO - Chipset Model: Intel Iris 15:10:11 INFO - Type: GPU 15:10:11 INFO - Bus: Built-In 15:10:11 INFO - VRAM (Dynamic, Max): 1536 MB 15:10:11 INFO - Vendor: Intel (0x8086) 15:10:11 INFO - Device ID: 0x0a2e 15:10:11 INFO - Revision ID: 0x0009 15:10:11 INFO - Displays: 15:10:11 INFO - Display: 15:10:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:10:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:10:11 INFO - Display Serial Number: 1600x1200 60 15:10:11 INFO - Main Display: Yes 15:10:11 INFO - Mirror: Off 15:10:11 INFO - Online: Yes 15:10:11 INFO - Rotation: Supported 15:10:11 INFO - Return code: 0 15:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:10:11 INFO - Python 2.7.3 15:10:11 INFO - Return code: 0 15:10:11 INFO - grabbing minidump binary from tooltool 15:10:11 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')]} 15:10:11 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 0x10aaeed30>, '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 0x10a914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aae6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:10:11 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 15:10:11 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 15:10:11 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 15:10:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:10:11 INFO - Return code: 0 15:10:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:10:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:10:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:10:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:10:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0340', '--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 15:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0340 --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 15:10:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cWPlHB7Qb6/Render', 15:10:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:11 INFO - 'HOME': '/Users/cltbld', 15:10:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:11 INFO - 'LOGNAME': 'cltbld', 15:10:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:10:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:11 INFO - 'MOZ_NO_REMOTE': '1', 15:10:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:10:11 INFO - 'NO_EM_RESTART': '1', 15:10:11 INFO - 'PAGER': '/bin/cat', 15:10:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:11 INFO - 'PWD': '/builds/slave/test', 15:10:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:10:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:11 INFO - 'SHELL': '/bin/bash', 15:10:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0wYuLKZiS/Listeners', 15:10:11 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 15:10:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:11 INFO - 'USER': 'cltbld', 15:10:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:11 INFO - 'XPC_FLAGS': '0x0', 15:10:11 INFO - 'XPC_SERVICE_NAME': '0', 15:10:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0340', '--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 15:10:11 INFO - mozversion application_buildid: 20160912131516 15:10:11 INFO - mozversion application_changeset: 494bab58654bc4fa444742016ee4f947e093be27 15:10:11 INFO - mozversion application_display_name: Nightly 15:10:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:10:11 INFO - mozversion application_name: Firefox 15:10:11 INFO - mozversion application_remotingname: firefox 15:10:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:10:11 INFO - mozversion application_vendor: Mozilla 15:10:11 INFO - mozversion application_version: 51.0a1 15:10:11 INFO - mozversion platform_buildid: 20160912131516 15:10:11 INFO - mozversion platform_changeset: 494bab58654bc4fa444742016ee4f947e093be27 15:10:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:10:11 INFO - mozversion platform_version: 51.0a1 15:10:11 INFO - using testdate: 1473718211 15:10:11 INFO - actual date: 1473718211 15:10:11 INFO - starting webserver on 'localhost:49275' 15:10:11 INFO - SUITE-START | Running 2 tests 15:10:11 INFO - TEST-START | dromaeo_css 15:10:11 INFO - operating with platform_type : mac_ 15:10:11 INFO - Initialising browser for dromaeo_css test... 15:10:11 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpCLixBE/profile http://localhost:49275/getInfo.html) 15:10:14 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 15:10:14 INFO - PROCESS | 1659 | colorDepth:24 15:10:14 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 15:10:14 INFO - PROCESS | 1659 | __metrics 15:10:14 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 15:10:15 INFO - TEST-INFO | 1659: exit 0 15:10:15 INFO - Browser initialized. 15:10:15 INFO - Running cycle 1/1 for dromaeo_css test... 15:10:15 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpCLixBE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:10:27 INFO - PROCESS | 1662 | RSS: Main: 244174848 15:10:27 INFO - PROCESS | 1662 | 15:11:16 INFO - PROCESS | 1662 | 1473718276662 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] 15:13:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 15:13:28 INFO - PROCESS | 1662 | RSS: Main: 267145216 15:13:28 INFO - PROCESS | 1662 | 15:16:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 15:16:31 INFO - PROCESS | 1662 | RSS: Main: 273747968 15:16:31 INFO - PROCESS | 1662 | 15:19:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 15:19:34 INFO - PROCESS | 1662 | RSS: Main: 275345408 15:19:34 INFO - PROCESS | 1662 | 15:22:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 15:22:36 INFO - PROCESS | 1662 | RSS: Main: 269762560 15:22:36 INFO - PROCESS | 1662 | 15:25:39 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 15:25:39 INFO - PROCESS | 1662 | RSS: Main: 276078592 15:25:39 INFO - PROCESS | 1662 | 15:28:42 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 15:28:43 INFO - PROCESS | 1662 | RSS: Main: 278769664 15:28:43 INFO - PROCESS | 1662 | 15:28:43 INFO - PROCESS | 1662 | __start_tp_report 15:28:43 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 15:28:43 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 15:28:43 INFO - PROCESS | 1662 | |i|pagename|runs| 15:28:43 INFO - PROCESS | 1662 | |0;dojo.html;2832.7450529452344;3074.1392410125163;3312.6521715219915;3330.6336303006665;3373.6626337366283;5499.477549632784;5520.806771762984;5558.221848941152;5567.49892509674;5573.860653483665;5432.130859062541;5513.117901135819;5516.9448305516935;5540.36285827129;5589.524890384322;5061.999999999991;5111.258867464216;5163.974180129113;5171.974140129294;5181.3264275644115;143.34960971080778;5101.489851014888;5120.487951204868;5125.820596279131;5255.684658920458;4996.525330093636;5024.17101178307;5059.6458247922665;5111.131107711661;5129.615278854101;5113.309703190096;5133.332666753336;5141.254518094893;5149.407818100929;5151.742412879341;4162.687798415131;5128.538431541179;5136.5890728741615;5147.7683504242395;5151.793928242903;4823.614110871122;5457.727113644302;5555.833325000256;5654.575906807006;5663.971680141614;4652.209124448852;5262.473752624745;5605.1872478490795;6088.117223002684;6262.561620686553;5164.8967020659375;6168.96915515424;6172.938270617326;6176.87646247077;6176.938230617681;5214;5273.549773812194;5516.034693928546;5563.805266815642;5623.718814059281;6171.938280617136;6172;6180.938190618126;6202.162708034358;6208.1308616793685;5855.2973643163095;5909.3204281507315;5910.615809972338;5962.582619216613;5964.701764911737;5968.880622387528;6011.639301641915;6037.0642550404755;6074.119252708332;6105.419985101408;5974.193483879708;6043.093535969552;6046.304674962347;6058.727357268934;6070.878582428327;6055.061465472859;6082.75668973245;6087.1173679816175;6088.056351265561;6089.543284253698;4762.452317983407;4775.283707443842;4815.374001379798;4816.831410900601;4886.169351210303;4325.956740432555;4400.537943515885;4403.185410699031;4441.755703436277;4496.0558282760985;5490.286262785812;5573.470520300565;5633.718314084279;5654.9151762723595;5670.8015219467125;5955.7617695292565;6034.577579569388;6035.637861728311;6096.237970253718;6125.601835880653;5579.609427340048;5585.469380408855;5589.077802162679;5623.859403514863;5669.348024977098;5282.022825777243;5290.338707661543;5297.125974214211;5303.337082864642;5319.308489896289;6104.428839602322;6116.755329786759;6123.801220575414;6230.096635987757;6231.626102433868;6064.3026052002815;6098.038923863867;6121.065954435751;6128.319797384666;6129.117548752169;6117.066276512034;6127.264728232641;6176.907346389898;6261.311255761953;6268;5877.617954832922;5965;6013.699315034143;6033.818985430268;6066.878662426639;6095.35998720128;6110.066740270037;6114.784027539525;6127.189528148541;6130.184124957687;5829.125631155191;5934.851628709144;5950.613210141327;5959.910601340896;5971.3132989705;5686.9715651421475;5934.495567876779;5946.197263369563;5954.791582294599;5959.0763431668165;5644.491995720405;5671.291088613923;5714.171505091515;5759.193712880284;5792.304923409218;5876.529877609866;5955.731992060454;5959.314678811992;5967.8959392512525;5972.641641501524;5735.168400581976;5766.163906233489;5783.7975670853;5785.3636100028125;5785.595008349375 15:28:43 INFO - PROCESS | 1662 | |1;ext.html;429.93765903943927;451.62063866352236;463.1177606659311;520.8411434512474;536.8377462793053;39593.80203098984;40810.183796324054;40914.567990120355;40941;40989.59010409895;39466.8026659867;42457.51398701046;42471.78764106183;42731;42946.570534294646;8667.479951202948;9697.709068727932;9892.307538472274;9895.257855660819;9902;9728.881178664473;9772.218222542215;9790.216782657406;9807.852882206773;9818.607255709763;8374.58127093646;9839.655612053579;9849.60601575936;9850.211983041376;9860.30977831552;9755.268354873377;9808.754781130458;9819.263555233387;9827.705168844946;9827.754306142404;9676.467794271312;9744;9746.220302375792;9750.609975600968;9782.853257201148;40372.394414083516;40707.592924070676;40839;40980.79509602463;41007.38488922668;3302.4220761366664;3332;3384.5430866832908;3511.3679537683206;3746.6815320697774;30823.845880770677;30851.69148308533;30984.8450757747;31333.529997050064;32038.199045023826;3153.7950033247994;3223.903282901493;3226.774125811194;3270.3622793555264;3320.5849268841393;494755;495027;495837;498834;499220;60167.69916150479;60599.69700151471;60642.393576064555;60702;60773.08840367398;34513.13717157041;34541.82729086339;35088;36232.27535449276;36357.63642363595;16785.412510562073;16958.152092395332;16960.57598560021;16973.660526789394;16995.49013529596;12897.48410063587;12981.610551683465;13048.282344471145;13067.281299528617;13098.410571524304;18775.436736897915;18835.529111772314;18906.716399254023;19044.143013564422;19068.8093119067;6612.768553100618;7853.764387068397;7963.721269755531;8082.515049097073;8148.22591853773;843.6878355008686;900.122380678846;1028.8662473878499;1057.1859668055554;1112.3159257056852;24622.384440389134;29621;30590.694093059228;30605;31417;960.9423434593947;1011.2314640872843;1071.7802850415646;1106.0377471599759;1115.760111576015;15525.922370388076;15595.064296142042;15615.453459128876;15734.370625174864;16065.518034458984;663280;666550;672495;672660;674913;57695.71152144212;57864.13203801947;58283;58299.708501458066;58346;1292.5282271970664;1321.253491777143;1332.3737843213673;1335.8664133586758;1348.3797453171455;26429.867850660623;28950.710492894803;29803.50982450912;30371.696283036887;30659;51500.227496586835;51502;51954.22068669049;51988;52093.47906520886;581710;581864;582413;585645;588135;26571.937122514883;26670.733292666824;27015.189544313704;27030.594541082293;27063.729362706123;46836.29745553888;47070.10007201239;48444.515554844;49913.251301229786;50623.74688126536;31076;32753;35824.64175358213;39785;40780.18439631131;35199.82400087983;36410.907672769856;39489;39867.60132398639;40543.3918491217;28135.43729125447;33261.667383325854;38474;39018.6098139015;39324.01689957774;30516.237094072825;34535.48196777;35185.64814351926;35231.11922201965;35397.46903796497;23790;24021.399465013514;24703.505929880943;25650.61524077178;25969.091081812046 15:28:43 INFO - PROCESS | 1662 | |2;jquery.html;356.5632100676671;375.5268361864052;376.2155904938203;383.2067620026543;398.3109221047588;18897.338593149227;19702.802971970275;20067.397978060646;20631.587368252625;20804.959752012375;19687.704684429744;19948;20201.595968080666;20300.289489867857;20562.69155962658;4980.576650984671;5769.538436925046;5885.911711324334;5902.380250073745;6026.0960855871535;5836.6789826559525;5875.676837773921;5911;5930.258717660293;5931.733072011748;5513.3108361454815;5738.397468265814;5761.654300741948;5839.124131380285;5862.091375836731;5799.391063938289;5849.298084229919;5856.560757943129;5857.531397488191;5880.882382352372;5107.3105130807235;5107.438181799999;5366.973165134188;5420.186971954238;5458.590605704588;12757.681057973532;13012.284324362156;13019.934900325532;20365.8981705092;23388.298351049496;2117.523557199633;2162.2972533926536;2170.175333373308;2234.854734442256;2284.4859906521065;9522.90477095234;9545;9717.222622190207;9966.451845148515;10133.695989120264;1808.8733788634802;1811.8278763517444;1815.9092045397676;1848.5655870870335;1871.0925201277369;121082;130780;131178;131689;131722;9070.047644997312;10680.359178449318;11258.324500529994;11345.943270283597;11436.714082148013;11289.887101128883;12446;13818.723625527635;14324.14055156694;14351.282435878164;7550.811229719301;7659.578723170279;8377.287930525974;8459;8484.36367272457;7585;7826.608669566499;8368.497890126611;8420.200080992296;8527.573621318908;3810.3903375459795;4407.801648925806;4470.664700147502;4507.81968721254;4514.751688657156;1880.6647280430773;1912.931717304574;1923.394130848778;1929.305450037985;1935.3032908153054;816.7631386897851;819.2876887305441;847.4322204123253;867.6052396159732;906.8549032154824;15104.924475377773;18435.57546188905;20429.080691368923;20716.653417527814;20937.848418336835;829.3903980574273;881.1805021330097;927.2674587076269;932.7574830544107;935.9564086044012;10335;10785.56857725698;10788.298760580537;10873.891261087447;10993.285436446606;538582;606497;609332;612300;626883;138430.92353614705;144116;146613;147417;154259;969.7333233360882;992.2508506077864;1013.189448441244;1016.3190662256306;1018.0888105145852;17386.304547817945;18724.625507490047;18891.338803141825;19248.61502769965;19480.220791168762;39855.40216896807;51187.488125118274;57349.15677701437;58649.827003460545;58713.825723486145;54931;59369;76290.474190517;87789.56105219689;88606;6759.459243260429;6958.269381714847;7047.40097091753;7101.005859179822;7265.636718164176;7987.480813747088;11059.281146725438;11537.096379097355;11539.788322226046;12140.271583705005;9610;9803.558839852367;10253.538590763583;10298.176145908159;11230.270032447865;10998.615048473264;11088.168387370819;11380.829287560859;11437.913398227156;11608.709782255512;11351.943240283746;11381.715457113638;11585.478653460446;11658.06735461178;11800.055995520166;8057.637406316847;8133.918660813316;8223.95888020556;8267.793305167179;8355.248027677539;11198.160137989846;11352.262102963281;11451.25566838153;11472.598459053894;11488.310701357945 15:28:43 INFO - PROCESS | 1662 | |3;mootools.html;1411.4523285469727;1492.5969988103216;1652.6198974235926;1692.3315290460268;1853.1568136497895;7954.363650907928;8210.91789082109;8511.276541493973;9069;9114.908850911488;8524.957375213131;8814.86777698335;8825.602847871845;8887.64449422024;8893.599788009538;2165.2529877192424;2341.262502311766;2381.6546600742927;2405.374602603327;2418.7218469875925;2374.6081896487144;2394.5091256292503;2401.5557121932497;2417.9274621761374;2425.8059355251626;2383.535210633927;2402.027178992511;2410.433548116191;2416.5891798394227;2419.733829278778;2330.755270696586;2385.8091352691745;2398.784109430151;2398.9880050599722;2405.747396523375;2053.2300387354744;2357.2928121563464;2376.3821406434276;2376.702912135983;2380.0598763488324;8639.913600863974;8875.73372798817;8875.866861997076;8879.422837515542;8881.378303518692;5215.58275337972;5369.570434365241;5380.031594313022;5382.300300960889;5385.030694474993;3462.7576069675133;3495.1436897959907;3519.788812671248;3576.910577235564;3609.8989808108518;1058.2433560004556;1059.8251288537383;1070.8929107089307;1078.7087486378634;1081.8214994525972;11671.007964323042;11711.882881171165;11753.588624398191;11808.114391420591;11830.994365478944;4558.726476411426;4718.528147185254;4824.131656301829;4839.080574690868;4868.391451068616;8685.565721714016;9976.650817221362;9998.700038998843;10077.848832267522;10095.24285678577;5255.816046438356;5309.787608495694;5353.9196912046355;5354.4645535446925;5359.276497672842;3812;4575.656825738083;4751.311059896296;4875.926861097087;4880;3856.8842934712;3938.5864484229314;3995.9200815983518;4011.6590089842107;4050.736702114353;1305.2299143505377;1306.0987918336318;1314.415085287038;1316.2168509736691;1326.9270190139634;743.4461326311932;849.1211595998112;900.819836032796;900.9414388064755;923.7598224461553;4561.301859085148;5683.943160568424;5894.941050589525;6006.789762358384;6033.607815491979;857.5883575883611;1011.0961835099854;1018.974525636865;1032.4063663393579;1046.539522610049;3379.104537297618;3472.340255351475;3876.1860009398342;3934.586868378779;3982.3230050891416;10577.524011419504;10948.4525773711;10961.342319460859;10979.560817567368;10987.121030317556;10174.135198508062;10738.979796919437;10767.676969690921;10890.891091088988;10911.963363480616;8071.4349995500925;8292.419530632918;8308.5430301334;8328.167183281503;8338.958305208556;5435.23906653061;5882.911756323573;5926.881462370815;6058.394160583906;6080.939190608037;8972.596233169654;9950.253730970351;10002.199824014207;10008.499575021075;10114.94942525312;9977.501124943868;10126;10136.290459667753;10344.637937672145;10361.119304859172;6936.4797640176175;7151.927210918404;7354.338109570164;7481;7622.618869056636;8256.628451719807;8263.545504997282;8480.236778689947;8489.36329775257;8496.872546911678;7457.962710186414;7573.734919277799;7604.619769011638;7694.9615251925625;7778.611069446618;7357.3746231568775;7512;7531.322181003736;7534.585597792174;7686.846263074595;7013.2986701329455;7017.824554386181;7135.464840136907;7150.570965742072;7177.1746249182015;4958.950410495849;5035.071879991701;5077.568406685473;5096.286519887145;5116.616253780907;4975.626827987988;5000.599952003904;5112.335396398445;5150.381954165524;5183.637145399785 15:28:43 INFO - PROCESS | 1662 | |4;prototype.html;301.421271159374;303.6128935607101;311.11022475719443;313.72078849823663;313.8509208126139;5075.918873261963;5201.167813149897;5485.424030476803;5683.8010669626465;5783.363829978704;5448.645838020526;5592.86017849553;5604.663720176793;5608.43915608439;5677.9716101419435;3890.474785903895;4249.1714115747445;4254.340577210522;4261.659067274611;4277.700560960734;4067.3898915162667;4236.809343579546;4248.617624413802;4250.3624456331645;4278.786060696968;4205.992715208626;4208.2845916194165;4216.494020717503;4218.92018826257;4226.788660566975;4211.789410529476;4219.367094935754;4230.61924426803;4262.87211383659;4264.9147017059795;3599.402928446265;3681.37416639172;3690.815459227055;3697.593264740903;3718.2935242303874;5349.812756553502;5414.835810300804;5440.809571664972;5465.289512363407;5483.369412517572;4867.537583929521;4890.266460030988;4910.9932463844825;4911.238757992517;4934.333864928225;4946.455889852114;5438.809641662523;5621.915671264894;5721.513671337942;5778.797742079007;3954.8418063277436;3991.3214753491975;3997.780122093284;4017.7388469749667;4027.4160246764054;6125.785597504066;6313.40022697843;6317.87364252717;6373.681315934231;6386.13787138735;3155.763317751178;3747.437884317374;4026.979865100656;4039.7576145431367;4058.5129784425467;4023.1752490739327;4075.5109386873764;4090.754554726726;4097.672186225095;4103.297131690375;4227.513835908849;4715.976420117878;4789.880252993703;4808.543188397097;4828.275758636162;5549.112142057252;5991.910121348184;6010.398960103955;6026.427489388501;6033.637981721111;1227.6071657069651;1318.5693522528165;1356.884664803483;1385.9376328065262;1397.1617029782253;725.8584576007628;774.6785084190111;801.5951944268107;818.3044412249541;856.5503110866845;4106.774127422961;4309.168603343566;4706.21149974274;4732.408448943882;4782.808687652525;4335.631471324909;4348.5211830535445;4514.367988481616;4849.677970590157;5166.003717843641;4113.457453454955;4240.236757383639;4755.476897541268;4768.16294304888;4770.403699537558;2405.6064216104737;2457.913973010936;2587.4436996045943;2694.703582605912;2738.2885696439726;4202.054537728986;4320.783960801948;5220.3735551734035;5266.473352664703;5326.7869285228935;5006.283728838648;5042.546170844642;5251.579873610102;5282.418933917266;5298.602604804655;4639.21647011797;4855.587275081587;5293.258943747878;5319.521243088141;5330.493603107776;4269.55631569751;4367.213901497761;4659.976700116477;5105.846824595268;5158.767855446589;4052.5422035760425;4409.22838503267;4541.636669066401;5104.846854594368;5124.871878203075;5919.585629005928;6200.3179650237535;6351.71417286214;6388.712507937061;6391.776287829904;3059.72462478378;3700.8334624941403;3919.549251836075;3952.308346039489;4028.1067898087854;6135.6932153393045;6143.232095988002;6152.538559607958;6164.657430176504;6166.260048794174;5736.082226843685;5937.287525496968;5943.673097979653;5950.761969521343;5972.641641501524;5251.684898906078;5264.894702106013;5868.67722275279;5899.144624029577;5978.282606087298;4665.393498845128;5824.223611798273;5856.677882716492;5861.32594751609;5889.764409423575;5241.056609810118;5724.885502290014;5885.293764748258;5984.371640977634;6026.487748541423;5053.898922021465;5401.405845357086;5809.390014048633;5838.5621078418435;5846.6442500335825 15:28:43 INFO - PROCESS | 1662 | |5;yui.html;698.3958875572631;703.9401650859677;706.5089762614979;747.1967634792597;767.2289349204051;15273;15367.308471118784;15786.526404207892;15813.130277834714;15917.761233581505;14200.076994995317;14303.356348964295;14368.928155359208;14399.712005759879;14400.423983040666;668.7057694614379;689.9857209901437;828.9378296627762;830.211299265697;843.9746807595751;803.5459965119688;839.7774589733725;842.8103676672761;844.025150950653;849.647396330522;845.5053793530792;890.5413711938357;891.790429249126;893.4282059481905;896.0457113174448;768.9423293253001;849.5370023337296;852.0371979662941;881.7442941547008;892.6340200517757;645.2708439463423;655.1613934164277;665.7769647168204;666.9266380698119;667.0661074495696;14157.07978981363;14367.568972930829;14378.640533986629;14414.783778243334;14438.49465268721;14156.362963666557;14259.358328875225;14316.713665726731;14322.785158222636;14387.568372948828;10067.798644027152;11520.366379849105;11528.308301501937;11609.361485118316;11630;3757.5490941086973;3834.482344883433;3834.5905626080266;3849.6727778138893;3986.5216174059296;113848.43075784651;117948;118751;118875;119868;10319;10407.94796026015;10413.219008574388;10472.16222702182;10491.265611407129;19904.90047549782;20950;20991.580168396547;21004.684929726067;21043.68434473484;7750.922490775133;7941.245581669732;7977.800554986055;7983.920160798318;8119.3910456715985;4430.20256353853;4458.551045768251;4533.591976722111;4551.840685575989;4616.884577885579;3512.2448673535314;3639.6178401268016;3663.1025398776937;3671.724620653462;3679.4848721179055;625.3746253746253;627.7457629659904;628.1300398947491;644.8484606117559;652.2433976587137;552.7540244591198;640.8624691173112;652.3150691773619;656.422348333473;683.1699485125596;9152.405093668891;9325.487098209664;9355;9357.906420935704;9424.811503770023;563.2199403825649;621.3724138620026;639.4436839949274;656.307595486766;663.5918909870395;4312;4557.248054071108;4571.954280457219;4584.885377865513;4595.058013107305;96862;131698;131740.0238996416;162959;165512;86056.13943860607;87177.56411217904;105265.87891411418;110433.89566104396;123435.38282308531;100979.49510252548;101992.49003754933;105353.41969870459;114548;117910.15673824325;8433.241008309282;9106.532643232485;9643.807123857623;9656.130948214413;9770.902290977;51195.976080477434;53091.93816123578;62774.29192810649;63290.683546581975;65041.34958650542;98596;99343.00656993626;100280;100818.98362032571;101426;12620.368981550702;12691.238525688488;12713.237205767684;12743.808842867178;13146.80279795823;11678.598568171874;12355.62933112008;14043.859561404255;14499.202543860189;14768.33542490612;11616.825747613624;13423.395947182595;13428.19430834153;13645.249511276976;14003.159810411407;13231.674131328373;13581.932090339486;13712.245826479639;13829.930850345683;13902.16587004783;13301.721738438628;13351.532696355582;13548.729025419634;13642.86357136416;13649.190036579152;10647.94676026615;10899.346039237671;11108.055815255793;11353.54585816558;11502.309861408343;10200.642977495752;10229;11030.669079927615;11095.5561777528;11190.104791616814 15:28:43 INFO - PROCESS | 1662 | __end_tp_report 15:28:43 INFO - PROCESS | 1662 | __start_cc_report 15:28:43 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,383 15:28:43 INFO - PROCESS | 1662 | __end_cc_report 15:28:43 INFO - PROCESS | 1662 | __startTimestamp1473719323108__endTimestamp 15:28:43 INFO - PROCESS | 1662 | 15:28:43 INFO - PROCESS | 1662 | ------- Summary: start ------- 15:28:43 INFO - PROCESS | 1662 | Number of tests: 6 15:28:43 INFO - PROCESS | 1662 | 15:28:43 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5544.75 Median:5714.17 stddev:764.35 (13.4%) stddev-sans-first:736.49 15:28:43 INFO - PROCESS | 1662 | Values: 2832.7 3074.1 3312.7 3330.6 3373.7 5499.5 5520.8 5558.2 5567.5 5573.9 5432.1 5513.1 5516.9 5540.4 5589.5 5062.0 5111.3 5164.0 5172.0 5181.3 143.3 5101.5 5120.5 5125.8 5255.7 4996.5 5024.2 5059.6 5111.1 5129.6 5113.3 5133.3 5141.3 5149.4 5151.7 4162.7 5128.5 5136.6 5147.8 5151.8 4823.6 5457.7 5555.8 5654.6 5664.0 4652.2 5262.5 5605.2 6088.1 6262.6 5164.9 6169.0 6172.9 6176.9 6176.9 5214.0 5273.5 5516.0 5563.8 5623.7 6171.9 6172.0 6180.9 6202.2 6208.1 5855.3 5909.3 5910.6 5962.6 5964.7 5968.9 6011.6 6037.1 6074.1 6105.4 5974.2 6043.1 6046.3 6058.7 6070.9 6055.1 6082.8 6087.1 6088.1 6089.5 4762.5 4775.3 4815.4 4816.8 4886.2 4326.0 4400.5 4403.2 4441.8 4496.1 5490.3 5573.5 5633.7 5654.9 5670.8 5955.8 6034.6 6035.6 6096.2 6125.6 5579.6 5585.5 5589.1 5623.9 5669.3 5282.0 5290.3 5297.1 5303.3 5319.3 6104.4 6116.8 6123.8 6230.1 6231.6 6064.3 6098.0 6121.1 6128.3 6129.1 6117.1 6127.3 6176.9 6261.3 6268.0 5877.6 5965.0 6013.7 6033.8 6066.9 6095.4 6110.1 6114.8 6127.2 6130.2 5829.1 5934.9 5950.6 5959.9 5971.3 5687.0 5934.5 5946.2 5954.8 5959.1 5644.5 5671.3 5714.2 5759.2 5792.3 5876.5 5955.7 5959.3 5967.9 5972.6 5735.2 5766.2 5783.8 5785.4 5785.6 15:28:43 INFO - PROCESS | 1662 | 15:28:43 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:70709.96 Median:39826.30 stddev:157315.11 (395.0%) stddev-sans-first:157667.93 15:28:43 INFO - PROCESS | 1662 | Values: 429.9 451.6 463.1 520.8 536.8 39593.8 40810.2 40914.6 40941.0 40989.6 39466.8 42457.5 42471.8 42731.0 42946.6 8667.5 9697.7 9892.3 9895.3 9902.0 9728.9 9772.2 9790.2 9807.9 9818.6 8374.6 9839.7 9849.6 9850.2 9860.3 9755.3 9808.8 9819.3 9827.7 9827.8 9676.5 9744.0 9746.2 9750.6 9782.9 40372.4 40707.6 40839.0 40980.8 41007.4 3302.4 3332.0 3384.5 3511.4 3746.7 30823.8 30851.7 30984.8 31333.5 32038.2 3153.8 3223.9 3226.8 3270.4 3320.6 494755.0 495027.0 495837.0 498834.0 499220.0 60167.7 60599.7 60642.4 60702.0 60773.1 34513.1 34541.8 35088.0 36232.3 36357.6 16785.4 16958.2 16960.6 16973.7 16995.5 12897.5 12981.6 13048.3 13067.3 13098.4 18775.4 18835.5 18906.7 19044.1 19068.8 6612.8 7853.8 7963.7 8082.5 8148.2 843.7 900.1 1028.9 1057.2 1112.3 24622.4 29621.0 30590.7 30605.0 31417.0 960.9 1011.2 1071.8 1106.0 1115.8 15525.9 15595.1 15615.5 15734.4 16065.5 663280.0 666550.0 672495.0 672660.0 674913.0 57695.7 57864.1 58283.0 58299.7 58346.0 1292.5 1321.3 1332.4 1335.9 1348.4 26429.9 28950.7 29803.5 30371.7 30659.0 51500.2 51502.0 51954.2 51988.0 52093.5 581710.0 581864.0 582413.0 585645.0 588135.0 26571.9 26670.7 27015.2 27030.6 27063.7 46836.3 47070.1 48444.5 49913.3 50623.7 31076.0 32753.0 35824.6 39785.0 40780.2 35199.8 36410.9 39489.0 39867.6 40543.4 28135.4 33261.7 38474.0 39018.6 39324.0 30516.2 34535.5 35185.6 35231.1 35397.5 23790.0 24021.4 24703.5 25650.6 25969.1 15:28:43 INFO - PROCESS | 1662 | 15:28:43 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:35237.24 Median:366.05 stddev:100930.24 (27573.2%) stddev-sans-first:101179.39 15:28:43 INFO - PROCESS | 1662 | Values: 356.6 375.5 376.2 383.2 398.3 18897.3 19702.8 20067.4 20631.6 20805.0 19687.7 19948.0 20201.6 20300.3 20562.7 4980.6 5769.5 5885.9 5902.4 6026.1 5836.7 5875.7 5911.0 5930.3 5931.7 5513.3 5738.4 5761.7 5839.1 5862.1 5799.4 5849.3 5856.6 5857.5 5880.9 5107.3 5107.4 5367.0 5420.2 5458.6 12757.7 13012.3 13019.9 20365.9 23388.3 2117.5 2162.3 2170.2 2234.9 2284.5 9522.9 9545.0 9717.2 9966.5 10133.7 1808.9 1811.8 1815.9 1848.6 1871.1 121082.0 130780.0 131178.0 131689.0 131722.0 9070.0 10680.4 11258.3 11345.9 11436.7 11289.9 12446.0 13818.7 14324.1 14351.3 7550.8 7659.6 8377.3 8459.0 8484.4 7585.0 7826.6 8368.5 8420.2 8527.6 3810.4 4407.8 4470.7 4507.8 4514.8 1880.7 1912.9 1923.4 1929.3 1935.3 816.8 819.3 847.4 867.6 906.9 15104.9 18435.6 20429.1 20716.7 20937.8 829.4 881.2 927.3 932.8 936.0 10335.0 10785.6 10788.3 10873.9 10993.3 538582.0 606497.0 609332.0 612300.0 626883.0 138430.9 144116.0 146613.0 147417.0 154259.0 969.7 992.3 1013.2 1016.3 1018.1 17386.3 18724.6 18891.3 19248.6 19480.2 39855.4 51187.5 57349.2 58649.8 58713.8 54931.0 59369.0 76290.5 87789.6 88606.0 6759.5 6958.3 7047.4 7101.0 7265.6 7987.5 11059.3 11537.1 11539.8 12140.3 9610.0 9803.6 10253.5 10298.2 11230.3 10998.6 11088.2 11380.8 11437.9 11608.7 11351.9 11381.7 11585.5 11658.1 11800.1 8057.6 8133.9 8224.0 8267.8 8355.2 11198.2 11352.3 11451.3 11472.6 11488.3 15:28:43 INFO - PROCESS | 1662 | 15:28:43 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5725.73 Median:4853.74 stddev:3214.56 (66.2%) stddev-sans-first:3207.22 15:28:43 INFO - PROCESS | 1662 | Values: 1411.5 1492.6 1652.6 1692.3 1853.2 7954.4 8210.9 8511.3 9069.0 9114.9 8525.0 8814.9 8825.6 8887.6 8893.6 2165.3 2341.3 2381.7 2405.4 2418.7 2374.6 2394.5 2401.6 2417.9 2425.8 2383.5 2402.0 2410.4 2416.6 2419.7 2330.8 2385.8 2398.8 2399.0 2405.7 2053.2 2357.3 2376.4 2376.7 2380.1 8639.9 8875.7 8875.9 8879.4 8881.4 5215.6 5369.6 5380.0 5382.3 5385.0 3462.8 3495.1 3519.8 3576.9 3609.9 1058.2 1059.8 1070.9 1078.7 1081.8 11671.0 11711.9 11753.6 11808.1 11831.0 4558.7 4718.5 4824.1 4839.1 4868.4 8685.6 9976.7 9998.7 10077.8 10095.2 5255.8 5309.8 5353.9 5354.5 5359.3 3812.0 4575.7 4751.3 4875.9 4880.0 3856.9 3938.6 3995.9 4011.7 4050.7 1305.2 1306.1 1314.4 1316.2 1326.9 743.4 849.1 900.8 900.9 923.8 4561.3 5683.9 5894.9 6006.8 6033.6 857.6 1011.1 1019.0 1032.4 1046.5 3379.1 3472.3 3876.2 3934.6 3982.3 10577.5 10948.5 10961.3 10979.6 10987.1 10174.1 10739.0 10767.7 10890.9 10912.0 8071.4 8292.4 8308.5 8328.2 8339.0 5435.2 5882.9 5926.9 6058.4 6080.9 8972.6 9950.3 10002.2 10008.5 10114.9 9977.5 10126.0 10136.3 10344.6 10361.1 6936.5 7151.9 7354.3 7481.0 7622.6 8256.6 8263.5 8480.2 8489.4 8496.9 7458.0 7573.7 7604.6 7695.0 7778.6 7357.4 7512.0 7531.3 7534.6 7686.8 7013.3 7017.8 7135.5 7150.6 7177.2 4959.0 5035.1 5077.6 5096.3 5116.6 4975.6 5000.6 5112.3 5150.4 5183.6 15:28:43 INFO - PROCESS | 1662 | 15:28:43 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4557.45 Median:4878.90 stddev:1428.12 (29.3%) stddev-sans-first:1395.94 15:28:43 INFO - PROCESS | 1662 | Values: 301.4 303.6 311.1 313.7 313.9 5075.9 5201.2 5485.4 5683.8 5783.4 5448.6 5592.9 5604.7 5608.4 5678.0 3890.5 4249.2 4254.3 4261.7 4277.7 4067.4 4236.8 4248.6 4250.4 4278.8 4206.0 4208.3 4216.5 4218.9 4226.8 4211.8 4219.4 4230.6 4262.9 4264.9 3599.4 3681.4 3690.8 3697.6 3718.3 5349.8 5414.8 5440.8 5465.3 5483.4 4867.5 4890.3 4911.0 4911.2 4934.3 4946.5 5438.8 5621.9 5721.5 5778.8 3954.8 3991.3 3997.8 4017.7 4027.4 6125.8 6313.4 6317.9 6373.7 6386.1 3155.8 3747.4 4027.0 4039.8 4058.5 4023.2 4075.5 4090.8 4097.7 4103.3 4227.5 4716.0 4789.9 4808.5 4828.3 5549.1 5991.9 6010.4 6026.4 6033.6 1227.6 1318.6 1356.9 1385.9 1397.2 725.9 774.7 801.6 818.3 856.6 4106.8 4309.2 4706.2 4732.4 4782.8 4335.6 4348.5 4514.4 4849.7 5166.0 4113.5 4240.2 4755.5 4768.2 4770.4 2405.6 2457.9 2587.4 2694.7 2738.3 4202.1 4320.8 5220.4 5266.5 5326.8 5006.3 5042.5 5251.6 5282.4 5298.6 4639.2 4855.6 5293.3 5319.5 5330.5 4269.6 4367.2 4660.0 5105.8 5158.8 4052.5 4409.2 4541.6 5104.8 5124.9 5919.6 6200.3 6351.7 6388.7 6391.8 3059.7 3700.8 3919.5 3952.3 4028.1 6135.7 6143.2 6152.5 6164.7 6166.3 5736.1 5937.3 5943.7 5950.8 5972.6 5251.7 5264.9 5868.7 5899.1 5978.3 4665.4 5824.2 5856.7 5861.3 5889.8 5241.1 5724.9 5885.3 5984.4 6026.5 5053.9 5401.4 5809.4 5838.6 5846.6 15:28:43 INFO - PROCESS | 1662 | 15:28:43 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:24020.10 Median:3575.93 stddev:37998.32 (1062.6%) stddev-sans-first:38064.56 15:28:43 INFO - PROCESS | 1662 | Values: 698.4 703.9 706.5 747.2 767.2 15273.0 15367.3 15786.5 15813.1 15917.8 14200.1 14303.4 14368.9 14399.7 14400.4 668.7 690.0 828.9 830.2 844.0 803.5 839.8 842.8 844.0 849.6 845.5 890.5 891.8 893.4 896.0 768.9 849.5 852.0 881.7 892.6 645.3 655.2 665.8 666.9 667.1 14157.1 14367.6 14378.6 14414.8 14438.5 14156.4 14259.4 14316.7 14322.8 14387.6 10067.8 11520.4 11528.3 11609.4 11630.0 3757.5 3834.5 3834.6 3849.7 3986.5 113848.4 117948.0 118751.0 118875.0 119868.0 10319.0 10407.9 10413.2 10472.2 10491.3 19904.9 20950.0 20991.6 21004.7 21043.7 7750.9 7941.2 7977.8 7983.9 8119.4 4430.2 4458.6 4533.6 4551.8 4616.9 3512.2 3639.6 3663.1 3671.7 3679.5 625.4 627.7 628.1 644.8 652.2 552.8 640.9 652.3 656.4 683.2 9152.4 9325.5 9355.0 9357.9 9424.8 563.2 621.4 639.4 656.3 663.6 4312.0 4557.2 4572.0 4584.9 4595.1 96862.0 131698.0 131740.0 162959.0 165512.0 86056.1 87177.6 105265.9 110433.9 123435.4 100979.5 101992.5 105353.4 114548.0 117910.2 8433.2 9106.5 9643.8 9656.1 9770.9 51196.0 53091.9 62774.3 63290.7 65041.3 98596.0 99343.0 100280.0 100819.0 101426.0 12620.4 12691.2 12713.2 12743.8 13146.8 11678.6 12355.6 14043.9 14499.2 14768.3 11616.8 13423.4 13428.2 13645.2 14003.2 13231.7 13581.9 13712.2 13829.9 13902.2 13301.7 13351.5 13548.7 13642.9 13649.2 10647.9 10899.3 11108.1 11353.5 11502.3 10200.6 10229.0 11030.7 11095.6 11190.1 15:28:43 INFO - PROCESS | 1662 | -------- Summary: end -------- 15:28:43 INFO - PROCESS | 1662 | 15:28:43 INFO - TEST-INFO | 1662: exit 0 15:28:43 INFO - TEST-OK | dromaeo_css | took 1112082ms 15:28:43 INFO - TEST-START | kraken 15:28:43 INFO - operating with platform_type : mac_ 15:28:43 INFO - Initialising browser for kraken test... 15:28:43 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpnAY_Ta/profile http://localhost:49275/getInfo.html) 15:28:46 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 15:28:46 INFO - PROCESS | 1671 | colorDepth:24 15:28:46 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 15:28:46 INFO - PROCESS | 1671 | __metrics 15:28:46 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 15:28:47 INFO - TEST-INFO | 1671: exit 0 15:28:47 INFO - Browser initialized. 15:28:47 INFO - Running cycle 1/1 for kraken test... 15:28:47 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpnAY_Ta/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:28:59 INFO - PROCESS | 1673 | RSS: Main: 244391936 15:28:59 INFO - PROCESS | 1673 | 15:29:02 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 15:29:03 INFO - PROCESS | 1673 | RSS: Main: 257110016 15:29:03 INFO - PROCESS | 1673 | 15:29:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 15:29:06 INFO - PROCESS | 1673 | RSS: Main: 265814016 15:29:06 INFO - PROCESS | 1673 | 15:29:10 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 15:29:10 INFO - PROCESS | 1673 | RSS: Main: 265469952 15:29:10 INFO - PROCESS | 1673 | 15:29:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 15:29:13 INFO - PROCESS | 1673 | RSS: Main: 267649024 15:29:13 INFO - PROCESS | 1673 | 15:29:15 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:29:16 INFO - PROCESS | 1673 | RSS: Main: 268382208 15:29:16 INFO - PROCESS | 1673 | 15:29:19 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 15:29:19 INFO - PROCESS | 1673 | RSS: Main: 268959744 15:29:19 INFO - PROCESS | 1673 | 15:29:22 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 15:29:23 INFO - PROCESS | 1673 | RSS: Main: 269148160 15:29:23 INFO - PROCESS | 1673 | 15:29:26 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 15:29:26 INFO - PROCESS | 1673 | RSS: Main: 269316096 15:29:26 INFO - PROCESS | 1673 | 15:29:28 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:29:29 INFO - PROCESS | 1673 | RSS: Main: 269078528 15:29:29 INFO - PROCESS | 1673 | 15:29:31 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:29:31 INFO - PROCESS | 1673 | RSS: Main: 269238272 15:29:31 INFO - PROCESS | 1673 | 15:29:33 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:29:34 INFO - PROCESS | 1673 | RSS: Main: 269623296 15:29:34 INFO - PROCESS | 1673 | 15:29:37 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:29:38 INFO - PROCESS | 1673 | RSS: Main: 269688832 15:29:38 INFO - PROCESS | 1673 | 15:29:41 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:29:41 INFO - PROCESS | 1673 | RSS: Main: 269688832 15:29:41 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 15:29:43 INFO - PROCESS | 1673 | RSS: Main: 271613952 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | __start_tp_report 15:29:43 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 15:29:43 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 15:29:43 INFO - PROCESS | 1673 | |i|pagename|runs| 15:29:43 INFO - PROCESS | 1673 | |0;ai-astar;116;116;110;118;119;116;100;119;117;119 15:29:43 INFO - PROCESS | 1673 | |1;audio-beat-detection;144;141;161;169;118;162;137;138;169;135 15:29:43 INFO - PROCESS | 1673 | |2;audio-dft;202;280;203;274;218;196;196;231;274;213 15:29:43 INFO - PROCESS | 1673 | |3;audio-fft;82;108;77;148;59;61;82;85;163;67 15:29:43 INFO - PROCESS | 1673 | |4;audio-oscillator;68;72;116;72;72;98;140;98;59;118 15:29:43 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;90;88;75;125;133;90;85;88;87;87 15:29:43 INFO - PROCESS | 1673 | |6;imaging-darkroom;100;102;100;100;103;101;100;101;101;101 15:29:43 INFO - PROCESS | 1673 | |7;imaging-desaturate;94;95;98;127;90;92;92;91;144;146 15:29:43 INFO - PROCESS | 1673 | |8;json-parse-financial;54;58;65;74;67;56;59;56;57;56 15:29:43 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;68;69;73;74;75;76;80;91;62;69 15:29:43 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;87;85;89;87;86;85;88;84;102;108 15:29:43 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;144;138;141;140;135;157;160;163;184;165 15:29:43 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;184;236;171;173;164;169;167;175;170;169 15:29:43 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;72;71;68;69;68;69;72;81;89;85 15:29:43 INFO - PROCESS | 1673 | __end_tp_report 15:29:43 INFO - PROCESS | 1673 | __start_cc_report 15:29:43 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,956 15:29:43 INFO - PROCESS | 1673 | __end_cc_report 15:29:43 INFO - PROCESS | 1673 | __startTimestamp1473719383756__endTimestamp 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | ------- Summary: start ------- 15:29:43 INFO - PROCESS | 1673 | Number of tests: 14 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:115.00 Median:117.50 stddev:5.91 (5.0%) stddev-sans-first:6.25 15:29:43 INFO - PROCESS | 1673 | Values: 116.0 116.0 110.0 118.0 119.0 116.0 100.0 119.0 117.0 119.0 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:147.40 Median:152.50 stddev:16.99 (11.1%) stddev-sans-first:17.98 15:29:43 INFO - PROCESS | 1673 | Values: 144.0 141.0 161.0 169.0 118.0 162.0 137.0 138.0 169.0 135.0 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:228.70 Median:224.50 stddev:34.34 (15.3%) stddev-sans-first:35.04 15:29:43 INFO - PROCESS | 1673 | Values: 202.0 280.0 203.0 274.0 218.0 196.0 196.0 231.0 274.0 213.0 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:93.20 Median:72.00 stddev:35.85 (49.8%) stddev-sans-first:37.80 15:29:43 INFO - PROCESS | 1673 | Values: 82.0 108.0 77.0 148.0 59.0 61.0 82.0 85.0 163.0 67.0 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:72.00 stddev:26.85 (37.3%) stddev-sans-first:27.12 15:29:43 INFO - PROCESS | 1673 | Values: 68.0 72.0 116.0 72.0 72.0 98.0 140.0 98.0 59.0 118.0 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:87.50 stddev:18.62 (21.3%) stddev-sans-first:19.67 15:29:43 INFO - PROCESS | 1673 | Values: 90.0 88.0 75.0 125.0 133.0 90.0 85.0 88.0 87.0 87.0 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:100.90 Median:101.00 stddev:0.99 (1.0%) stddev-sans-first:1.00 15:29:43 INFO - PROCESS | 1673 | Values: 100.0 102.0 100.0 100.0 103.0 101.0 100.0 101.0 101.0 101.0 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:106.90 Median:92.00 stddev:22.80 (24.8%) stddev-sans-first:23.70 15:29:43 INFO - PROCESS | 1673 | Values: 94.0 95.0 98.0 127.0 90.0 92.0 92.0 91.0 144.0 146.0 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:58.50 stddev:6.39 (10.9%) stddev-sans-first:6.37 15:29:43 INFO - PROCESS | 1673 | Values: 54.0 58.0 65.0 74.0 67.0 56.0 59.0 56.0 57.0 56.0 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:73.70 Median:74.50 stddev:7.89 (10.6%) stddev-sans-first:8.09 15:29:43 INFO - PROCESS | 1673 | Values: 68.0 69.0 73.0 74.0 75.0 76.0 80.0 91.0 62.0 69.0 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:86.50 stddev:8.12 (9.4%) stddev-sans-first:8.53 15:29:43 INFO - PROCESS | 1673 | Values: 87.0 85.0 89.0 87.0 86.0 85.0 88.0 84.0 102.0 108.0 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:152.70 Median:158.50 stddev:15.68 (9.9%) stddev-sans-first:16.31 15:29:43 INFO - PROCESS | 1673 | Values: 144.0 138.0 141.0 140.0 135.0 157.0 160.0 163.0 184.0 165.0 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.80 Median:172.00 stddev:21.15 (12.3%) stddev-sans-first:22.31 15:29:43 INFO - PROCESS | 1673 | Values: 184.0 236.0 171.0 173.0 164.0 169.0 167.0 175.0 170.0 169.0 15:29:43 INFO - PROCESS | 1673 | 15:29:43 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:72.00 stddev:7.69 (10.7%) stddev-sans-first:8.11 15:29:43 INFO - PROCESS | 1673 | Values: 72.0 71.0 68.0 69.0 68.0 69.0 72.0 81.0 89.0 85.0 15:29:43 INFO - PROCESS | 1673 | -------- Summary: end -------- 15:29:43 INFO - PROCESS | 1673 | 15:29:44 INFO - TEST-INFO | 1673: exit 0 15:29:44 INFO - TEST-OK | kraken | took 60845ms 15:29:44 INFO - SUITE-END | took 1172s 15:29:44 INFO - Completed test suite (00:19:33) 15:29:44 INFO - javascript benchmark 15:29:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7246.83349091693, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2832.7450529452344, 3074.1392410125163, 3312.6521715219915, 3330.6336303006665, 3373.6626337366283, 5499.477549632784, 5520.806771762984, 5558.221848941152, 5567.49892509674, 5573.860653483665, 5432.130859062541, 5513.117901135819, 5516.9448305516935, 5540.36285827129, 5589.524890384322, 5061.999999999991, 5111.258867464216, 5163.974180129113, 5171.974140129294, 5181.3264275644115, 143.34960971080778, 5101.489851014888, 5120.487951204868, 5125.820596279131, 5255.684658920458, 4996.525330093636, 5024.17101178307, 5059.6458247922665, 5111.131107711661, 5129.615278854101, 5113.309703190096, 5133.332666753336, 5141.254518094893, 5149.407818100929, 5151.742412879341, 4162.687798415131, 5128.538431541179, 5136.5890728741615, 5147.7683504242395, 5151.793928242903, 4823.614110871122, 5457.727113644302, 5555.833325000256, 5654.575906807006, 5663.971680141614, 4652.209124448852, 5262.473752624745, 5605.1872478490795, 6088.117223002684, 6262.561620686553, 5164.8967020659375, 6168.96915515424, 6172.938270617326, 6176.87646247077, 6176.938230617681, 5214.0, 5273.549773812194, 5516.034693928546, 5563.805266815642, 5623.718814059281, 6171.938280617136, 6172.0, 6180.938190618126, 6202.162708034358, 6208.1308616793685, 5855.2973643163095, 5909.3204281507315, 5910.615809972338, 5962.582619216613, 5964.701764911737, 5968.880622387528, 6011.639301641915, 6037.0642550404755, 6074.119252708332, 6105.419985101408, 5974.193483879708, 6043.093535969552, 6046.304674962347, 6058.727357268934, 6070.878582428327, 6055.061465472859, 6082.75668973245, 6087.1173679816175, 6088.056351265561, 6089.543284253698, 4762.452317983407, 4775.283707443842, 4815.374001379798, 4816.831410900601, 4886.169351210303, 4325.956740432555, 4400.537943515885, 4403.185410699031, 4441.755703436277, 4496.0558282760985, 5490.286262785812, 5573.470520300565, 5633.718314084279, 5654.9151762723595, 5670.8015219467125, 5955.7617695292565, 6034.577579569388, 6035.637861728311, 6096.237970253718, 6125.601835880653, 5579.609427340048, 5585.469380408855, 5589.077802162679, 5623.859403514863, 5669.348024977098, 5282.022825777243, 5290.338707661543, 5297.125974214211, 5303.337082864642, 5319.308489896289, 6104.428839602322, 6116.755329786759, 6123.801220575414, 6230.096635987757, 6231.626102433868, 6064.3026052002815, 6098.038923863867, 6121.065954435751, 6128.319797384666, 6129.117548752169, 6117.066276512034, 6127.264728232641, 6176.907346389898, 6261.311255761953, 6268.0, 5877.617954832922, 5965.0, 6013.699315034143, 6033.818985430268, 6066.878662426639, 6095.35998720128, 6110.066740270037, 6114.784027539525, 6127.189528148541, 6130.184124957687, 5829.125631155191, 5934.851628709144, 5950.613210141327, 5959.910601340896, 5971.3132989705, 5686.9715651421475, 5934.495567876779, 5946.197263369563, 5954.791582294599, 5959.0763431668165, 5644.491995720405, 5671.291088613923, 5714.171505091515, 5759.193712880284, 5792.304923409218, 5876.529877609866, 5955.731992060454, 5959.314678811992, 5967.8959392512525, 5972.641641501524, 5735.168400581976, 5766.163906233489, 5783.7975670853, 5785.3636100028125, 5785.595008349375], "value": 5498.1810149511575, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.93765903943927, 451.62063866352236, 463.1177606659311, 520.8411434512474, 536.8377462793053, 39593.80203098984, 40810.183796324054, 40914.567990120355, 40941.0, 40989.59010409895, 39466.8026659867, 42457.51398701046, 42471.78764106183, 42731.0, 42946.570534294646, 8667.479951202948, 9697.709068727932, 9892.307538472274, 9895.257855660819, 9902.0, 9728.881178664473, 9772.218222542215, 9790.216782657406, 9807.852882206773, 9818.607255709763, 8374.58127093646, 9839.655612053579, 9849.60601575936, 9850.211983041376, 9860.30977831552, 9755.268354873377, 9808.754781130458, 9819.263555233387, 9827.705168844946, 9827.754306142404, 9676.467794271312, 9744.0, 9746.220302375792, 9750.609975600968, 9782.853257201148, 40372.394414083516, 40707.592924070676, 40839.0, 40980.79509602463, 41007.38488922668, 3302.4220761366664, 3332.0, 3384.5430866832908, 3511.3679537683206, 3746.6815320697774, 30823.845880770677, 30851.69148308533, 30984.8450757747, 31333.529997050064, 32038.199045023826, 3153.7950033247994, 3223.903282901493, 3226.774125811194, 3270.3622793555264, 3320.5849268841393, 494755.0, 495027.0, 495837.0, 498834.0, 499220.0, 60167.69916150479, 60599.69700151471, 60642.393576064555, 60702.0, 60773.08840367398, 34513.13717157041, 34541.82729086339, 35088.0, 36232.27535449276, 36357.63642363595, 16785.412510562073, 16958.152092395332, 16960.57598560021, 16973.660526789394, 16995.49013529596, 12897.48410063587, 12981.610551683465, 13048.282344471145, 13067.281299528617, 13098.410571524304, 18775.436736897915, 18835.529111772314, 18906.716399254023, 19044.143013564422, 19068.8093119067, 6612.768553100618, 7853.764387068397, 7963.721269755531, 8082.515049097073, 8148.22591853773, 843.6878355008686, 900.122380678846, 1028.8662473878499, 1057.1859668055554, 1112.3159257056852, 24622.384440389134, 29621.0, 30590.694093059228, 30605.0, 31417.0, 960.9423434593947, 1011.2314640872843, 1071.7802850415646, 1106.0377471599759, 1115.760111576015, 15525.922370388076, 15595.064296142042, 15615.453459128876, 15734.370625174864, 16065.518034458984, 663280.0, 666550.0, 672495.0, 672660.0, 674913.0, 57695.71152144212, 57864.13203801947, 58283.0, 58299.708501458066, 58346.0, 1292.5282271970664, 1321.253491777143, 1332.3737843213673, 1335.8664133586758, 1348.3797453171455, 26429.867850660623, 28950.710492894803, 29803.50982450912, 30371.696283036887, 30659.0, 51500.227496586835, 51502.0, 51954.22068669049, 51988.0, 52093.47906520886, 581710.0, 581864.0, 582413.0, 585645.0, 588135.0, 26571.937122514883, 26670.733292666824, 27015.189544313704, 27030.594541082293, 27063.729362706123, 46836.29745553888, 47070.10007201239, 48444.515554844, 49913.251301229786, 50623.74688126536, 31076.0, 32753.0, 35824.64175358213, 39785.0, 40780.18439631131, 35199.82400087983, 36410.907672769856, 39489.0, 39867.60132398639, 40543.3918491217, 28135.43729125447, 33261.667383325854, 38474.0, 39018.6098139015, 39324.01689957774, 30516.237094072825, 34535.48196777, 35185.64814351926, 35231.11922201965, 35397.46903796497, 23790.0, 24021.399465013514, 24703.505929880943, 25650.61524077178, 25969.091081812046], "value": 19719.6707455383, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.5632100676671, 375.5268361864052, 376.2155904938203, 383.2067620026543, 398.3109221047588, 18897.338593149227, 19702.802971970275, 20067.397978060646, 20631.587368252625, 20804.959752012375, 19687.704684429744, 19948.0, 20201.595968080666, 20300.289489867857, 20562.69155962658, 4980.576650984671, 5769.538436925046, 5885.911711324334, 5902.380250073745, 6026.0960855871535, 5836.6789826559525, 5875.676837773921, 5911.0, 5930.258717660293, 5931.733072011748, 5513.3108361454815, 5738.397468265814, 5761.654300741948, 5839.124131380285, 5862.091375836731, 5799.391063938289, 5849.298084229919, 5856.560757943129, 5857.531397488191, 5880.882382352372, 5107.3105130807235, 5107.438181799999, 5366.973165134188, 5420.186971954238, 5458.590605704588, 12757.681057973532, 13012.284324362156, 13019.934900325532, 20365.8981705092, 23388.298351049496, 2117.523557199633, 2162.2972533926536, 2170.175333373308, 2234.854734442256, 2284.4859906521065, 9522.90477095234, 9545.0, 9717.222622190207, 9966.451845148515, 10133.695989120264, 1808.8733788634802, 1811.8278763517444, 1815.9092045397676, 1848.5655870870335, 1871.0925201277369, 121082.0, 130780.0, 131178.0, 131689.0, 131722.0, 9070.047644997312, 10680.359178449318, 11258.324500529994, 11345.943270283597, 11436.714082148013, 11289.887101128883, 12446.0, 13818.723625527635, 14324.14055156694, 14351.282435878164, 7550.811229719301, 7659.578723170279, 8377.287930525974, 8459.0, 8484.36367272457, 7585.0, 7826.608669566499, 8368.497890126611, 8420.200080992296, 8527.573621318908, 3810.3903375459795, 4407.801648925806, 4470.664700147502, 4507.81968721254, 4514.751688657156, 1880.6647280430773, 1912.931717304574, 1923.394130848778, 1929.305450037985, 1935.3032908153054, 816.7631386897851, 819.2876887305441, 847.4322204123253, 867.6052396159732, 906.8549032154824, 15104.924475377773, 18435.57546188905, 20429.080691368923, 20716.653417527814, 20937.848418336835, 829.3903980574273, 881.1805021330097, 927.2674587076269, 932.7574830544107, 935.9564086044012, 10335.0, 10785.56857725698, 10788.298760580537, 10873.891261087447, 10993.285436446606, 538582.0, 606497.0, 609332.0, 612300.0, 626883.0, 138430.92353614705, 144116.0, 146613.0, 147417.0, 154259.0, 969.7333233360882, 992.2508506077864, 1013.189448441244, 1016.3190662256306, 1018.0888105145852, 17386.304547817945, 18724.625507490047, 18891.338803141825, 19248.61502769965, 19480.220791168762, 39855.40216896807, 51187.488125118274, 57349.15677701437, 58649.827003460545, 58713.825723486145, 54931.0, 59369.0, 76290.474190517, 87789.56105219689, 88606.0, 6759.459243260429, 6958.269381714847, 7047.40097091753, 7101.005859179822, 7265.636718164176, 7987.480813747088, 11059.281146725438, 11537.096379097355, 11539.788322226046, 12140.271583705005, 9610.0, 9803.558839852367, 10253.538590763583, 10298.176145908159, 11230.270032447865, 10998.615048473264, 11088.168387370819, 11380.829287560859, 11437.913398227156, 11608.709782255512, 11351.943240283746, 11381.715457113638, 11585.478653460446, 11658.06735461178, 11800.055995520166, 8057.637406316847, 8133.918660813316, 8223.95888020556, 8267.793305167179, 8355.248027677539, 11198.160137989846, 11352.262102963281, 11451.25566838153, 11472.598459053894, 11488.310701357945], "value": 9240.435267733636, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.4523285469727, 1492.5969988103216, 1652.6198974235926, 1692.3315290460268, 1853.1568136497895, 7954.363650907928, 8210.91789082109, 8511.276541493973, 9069.0, 9114.908850911488, 8524.957375213131, 8814.86777698335, 8825.602847871845, 8887.64449422024, 8893.599788009538, 2165.2529877192424, 2341.262502311766, 2381.6546600742927, 2405.374602603327, 2418.7218469875925, 2374.6081896487144, 2394.5091256292503, 2401.5557121932497, 2417.9274621761374, 2425.8059355251626, 2383.535210633927, 2402.027178992511, 2410.433548116191, 2416.5891798394227, 2419.733829278778, 2330.755270696586, 2385.8091352691745, 2398.784109430151, 2398.9880050599722, 2405.747396523375, 2053.2300387354744, 2357.2928121563464, 2376.3821406434276, 2376.702912135983, 2380.0598763488324, 8639.913600863974, 8875.73372798817, 8875.866861997076, 8879.422837515542, 8881.378303518692, 5215.58275337972, 5369.570434365241, 5380.031594313022, 5382.300300960889, 5385.030694474993, 3462.7576069675133, 3495.1436897959907, 3519.788812671248, 3576.910577235564, 3609.8989808108518, 1058.2433560004556, 1059.8251288537383, 1070.8929107089307, 1078.7087486378634, 1081.8214994525972, 11671.007964323042, 11711.882881171165, 11753.588624398191, 11808.114391420591, 11830.994365478944, 4558.726476411426, 4718.528147185254, 4824.131656301829, 4839.080574690868, 4868.391451068616, 8685.565721714016, 9976.650817221362, 9998.700038998843, 10077.848832267522, 10095.24285678577, 5255.816046438356, 5309.787608495694, 5353.9196912046355, 5354.4645535446925, 5359.276497672842, 3812.0, 4575.656825738083, 4751.311059896296, 4875.926861097087, 4880.0, 3856.8842934712, 3938.5864484229314, 3995.9200815983518, 4011.6590089842107, 4050.736702114353, 1305.2299143505377, 1306.0987918336318, 1314.415085287038, 1316.2168509736691, 1326.9270190139634, 743.4461326311932, 849.1211595998112, 900.819836032796, 900.9414388064755, 923.7598224461553, 4561.301859085148, 5683.943160568424, 5894.941050589525, 6006.789762358384, 6033.607815491979, 857.5883575883611, 1011.0961835099854, 1018.974525636865, 1032.4063663393579, 1046.539522610049, 3379.104537297618, 3472.340255351475, 3876.1860009398342, 3934.586868378779, 3982.3230050891416, 10577.524011419504, 10948.4525773711, 10961.342319460859, 10979.560817567368, 10987.121030317556, 10174.135198508062, 10738.979796919437, 10767.676969690921, 10890.891091088988, 10911.963363480616, 8071.4349995500925, 8292.419530632918, 8308.5430301334, 8328.167183281503, 8338.958305208556, 5435.23906653061, 5882.911756323573, 5926.881462370815, 6058.394160583906, 6080.939190608037, 8972.596233169654, 9950.253730970351, 10002.199824014207, 10008.499575021075, 10114.94942525312, 9977.501124943868, 10126.0, 10136.290459667753, 10344.637937672145, 10361.119304859172, 6936.4797640176175, 7151.927210918404, 7354.338109570164, 7481.0, 7622.618869056636, 8256.628451719807, 8263.545504997282, 8480.236778689947, 8489.36329775257, 8496.872546911678, 7457.962710186414, 7573.734919277799, 7604.619769011638, 7694.9615251925625, 7778.611069446618, 7357.3746231568775, 7512.0, 7531.322181003736, 7534.585597792174, 7686.846263074595, 7013.2986701329455, 7017.824554386181, 7135.464840136907, 7150.570965742072, 7177.1746249182015, 4958.950410495849, 5035.071879991701, 5077.568406685473, 5096.286519887145, 5116.616253780907, 4975.626827987988, 5000.599952003904, 5112.335396398445, 5150.381954165524, 5183.637145399785], "value": 4603.375620532277, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.421271159374, 303.6128935607101, 311.11022475719443, 313.72078849823663, 313.8509208126139, 5075.918873261963, 5201.167813149897, 5485.424030476803, 5683.8010669626465, 5783.363829978704, 5448.645838020526, 5592.86017849553, 5604.663720176793, 5608.43915608439, 5677.9716101419435, 3890.474785903895, 4249.1714115747445, 4254.340577210522, 4261.659067274611, 4277.700560960734, 4067.3898915162667, 4236.809343579546, 4248.617624413802, 4250.3624456331645, 4278.786060696968, 4205.992715208626, 4208.2845916194165, 4216.494020717503, 4218.92018826257, 4226.788660566975, 4211.789410529476, 4219.367094935754, 4230.61924426803, 4262.87211383659, 4264.9147017059795, 3599.402928446265, 3681.37416639172, 3690.815459227055, 3697.593264740903, 3718.2935242303874, 5349.812756553502, 5414.835810300804, 5440.809571664972, 5465.289512363407, 5483.369412517572, 4867.537583929521, 4890.266460030988, 4910.9932463844825, 4911.238757992517, 4934.333864928225, 4946.455889852114, 5438.809641662523, 5621.915671264894, 5721.513671337942, 5778.797742079007, 3954.8418063277436, 3991.3214753491975, 3997.780122093284, 4017.7388469749667, 4027.4160246764054, 6125.785597504066, 6313.40022697843, 6317.87364252717, 6373.681315934231, 6386.13787138735, 3155.763317751178, 3747.437884317374, 4026.979865100656, 4039.7576145431367, 4058.5129784425467, 4023.1752490739327, 4075.5109386873764, 4090.754554726726, 4097.672186225095, 4103.297131690375, 4227.513835908849, 4715.976420117878, 4789.880252993703, 4808.543188397097, 4828.275758636162, 5549.112142057252, 5991.910121348184, 6010.398960103955, 6026.427489388501, 6033.637981721111, 1227.6071657069651, 1318.5693522528165, 1356.884664803483, 1385.9376328065262, 1397.1617029782253, 725.8584576007628, 774.6785084190111, 801.5951944268107, 818.3044412249541, 856.5503110866845, 4106.774127422961, 4309.168603343566, 4706.21149974274, 4732.408448943882, 4782.808687652525, 4335.631471324909, 4348.5211830535445, 4514.367988481616, 4849.677970590157, 5166.003717843641, 4113.457453454955, 4240.236757383639, 4755.476897541268, 4768.16294304888, 4770.403699537558, 2405.6064216104737, 2457.913973010936, 2587.4436996045943, 2694.703582605912, 2738.2885696439726, 4202.054537728986, 4320.783960801948, 5220.3735551734035, 5266.473352664703, 5326.7869285228935, 5006.283728838648, 5042.546170844642, 5251.579873610102, 5282.418933917266, 5298.602604804655, 4639.21647011797, 4855.587275081587, 5293.258943747878, 5319.521243088141, 5330.493603107776, 4269.55631569751, 4367.213901497761, 4659.976700116477, 5105.846824595268, 5158.767855446589, 4052.5422035760425, 4409.22838503267, 4541.636669066401, 5104.846854594368, 5124.871878203075, 5919.585629005928, 6200.3179650237535, 6351.71417286214, 6388.712507937061, 6391.776287829904, 3059.72462478378, 3700.8334624941403, 3919.549251836075, 3952.308346039489, 4028.1067898087854, 6135.6932153393045, 6143.232095988002, 6152.538559607958, 6164.657430176504, 6166.260048794174, 5736.082226843685, 5937.287525496968, 5943.673097979653, 5950.761969521343, 5972.641641501524, 5251.684898906078, 5264.894702106013, 5868.67722275279, 5899.144624029577, 5978.282606087298, 4665.393498845128, 5824.223611798273, 5856.677882716492, 5861.32594751609, 5889.764409423575, 5241.056609810118, 5724.885502290014, 5885.293764748258, 5984.371640977634, 6026.487748541423, 5053.898922021465, 5401.405845357086, 5809.390014048633, 5838.5621078418435, 5846.6442500335825], "value": 4095.4165793473994, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.3958875572631, 703.9401650859677, 706.5089762614979, 747.1967634792597, 767.2289349204051, 15273.0, 15367.308471118784, 15786.526404207892, 15813.130277834714, 15917.761233581505, 14200.076994995317, 14303.356348964295, 14368.928155359208, 14399.712005759879, 14400.423983040666, 668.7057694614379, 689.9857209901437, 828.9378296627762, 830.211299265697, 843.9746807595751, 803.5459965119688, 839.7774589733725, 842.8103676672761, 844.025150950653, 849.647396330522, 845.5053793530792, 890.5413711938357, 891.790429249126, 893.4282059481905, 896.0457113174448, 768.9423293253001, 849.5370023337296, 852.0371979662941, 881.7442941547008, 892.6340200517757, 645.2708439463423, 655.1613934164277, 665.7769647168204, 666.9266380698119, 667.0661074495696, 14157.07978981363, 14367.568972930829, 14378.640533986629, 14414.783778243334, 14438.49465268721, 14156.362963666557, 14259.358328875225, 14316.713665726731, 14322.785158222636, 14387.568372948828, 10067.798644027152, 11520.366379849105, 11528.308301501937, 11609.361485118316, 11630.0, 3757.5490941086973, 3834.482344883433, 3834.5905626080266, 3849.6727778138893, 3986.5216174059296, 113848.43075784651, 117948.0, 118751.0, 118875.0, 119868.0, 10319.0, 10407.94796026015, 10413.219008574388, 10472.16222702182, 10491.265611407129, 19904.90047549782, 20950.0, 20991.580168396547, 21004.684929726067, 21043.68434473484, 7750.922490775133, 7941.245581669732, 7977.800554986055, 7983.920160798318, 8119.3910456715985, 4430.20256353853, 4458.551045768251, 4533.591976722111, 4551.840685575989, 4616.884577885579, 3512.2448673535314, 3639.6178401268016, 3663.1025398776937, 3671.724620653462, 3679.4848721179055, 625.3746253746253, 627.7457629659904, 628.1300398947491, 644.8484606117559, 652.2433976587137, 552.7540244591198, 640.8624691173112, 652.3150691773619, 656.422348333473, 683.1699485125596, 9152.405093668891, 9325.487098209664, 9355.0, 9357.906420935704, 9424.811503770023, 563.2199403825649, 621.3724138620026, 639.4436839949274, 656.307595486766, 663.5918909870395, 4312.0, 4557.248054071108, 4571.954280457219, 4584.885377865513, 4595.058013107305, 96862.0, 131698.0, 131740.0238996416, 162959.0, 165512.0, 86056.13943860607, 87177.56411217904, 105265.87891411418, 110433.89566104396, 123435.38282308531, 100979.49510252548, 101992.49003754933, 105353.41969870459, 114548.0, 117910.15673824325, 8433.241008309282, 9106.532643232485, 9643.807123857623, 9656.130948214413, 9770.902290977, 51195.976080477434, 53091.93816123578, 62774.29192810649, 63290.683546581975, 65041.34958650542, 98596.0, 99343.00656993626, 100280.0, 100818.98362032571, 101426.0, 12620.368981550702, 12691.238525688488, 12713.237205767684, 12743.808842867178, 13146.80279795823, 11678.598568171874, 12355.62933112008, 14043.859561404255, 14499.202543860189, 14768.33542490612, 11616.825747613624, 13423.395947182595, 13428.19430834153, 13645.249511276976, 14003.159810411407, 13231.674131328373, 13581.932090339486, 13712.245826479639, 13829.930850345683, 13902.16587004783, 13301.721738438628, 13351.532696355582, 13548.729025419634, 13642.86357136416, 13649.190036579152, 10647.94676026615, 10899.346039237671, 11108.055815255793, 11353.54585816558, 11502.309861408343, 10200.642977495752, 10229.0, 11030.669079927615, 11095.5561777528, 11190.104791616814], "value": 7667.585434756993, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1607.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 110.0, 118.0, 119.0, 116.0, 100.0, 119.0, 117.0, 119.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 141.0, 161.0, 169.0, 118.0, 162.0, 137.0, 138.0, 169.0, 135.0], "value": 147.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 280.0, 203.0, 274.0, 218.0, 196.0, 196.0, 231.0, 274.0, 213.0], "value": 228.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 108.0, 77.0, 148.0, 59.0, 61.0, 82.0, 85.0, 163.0, 67.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 116.0, 72.0, 72.0, 98.0, 140.0, 98.0, 59.0, 118.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 75.0, 125.0, 133.0, 90.0, 85.0, 88.0, 87.0, 87.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 102.0, 100.0, 100.0, 103.0, 101.0, 100.0, 101.0, 101.0, 101.0], "value": 100.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 98.0, 127.0, 90.0, 92.0, 92.0, 91.0, 144.0, 146.0], "value": 106.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 58.0, 65.0, 74.0, 67.0, 56.0, 59.0, 56.0, 57.0, 56.0], "value": 60.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 73.0, 74.0, 75.0, 76.0, 80.0, 91.0, 62.0, 69.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 89.0, 87.0, 86.0, 85.0, 88.0, 84.0, 102.0, 108.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 138.0, 141.0, 140.0, 135.0, 157.0, 160.0, 163.0, 184.0, 165.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 236.0, 171.0, 173.0, 164.0, 169.0, 167.0, 175.0, 170.0, 169.0], "value": 177.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 68.0, 69.0, 68.0, 69.0, 72.0, 81.0, 89.0, 85.0], "value": 74.4, "unit": "score"}]}]} 15:29:44 INFO - Return code: 0 15:29:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:29:45 ERROR - # TBPL SUCCESS # 15:29:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:29:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:29:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:29:45 INFO - 2016-09-12 15:29:45.051 screenresolution[1678:10468] starting screenresolution argv=screenresolution get 15:29:45 INFO - 2016-09-12 15:29:45.053 screenresolution[1678:10468] Display 0: 1600x1200x32@60 15:29:45 INFO - 2016-09-12 15:29:45.059 screenresolution[1679:10471] starting screenresolution argv=screenresolution list 15:29:45 INFO - Available Modes on Display 0 15:29:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:29:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:29:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:29:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:29:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:29:45 INFO - Intel Iris: 15:29:45 INFO - Chipset Model: Intel Iris 15:29:45 INFO - Type: GPU 15:29:45 INFO - Bus: Built-In 15:29:45 INFO - VRAM (Dynamic, Max): 1536 MB 15:29:45 INFO - Vendor: Intel (0x8086) 15:29:45 INFO - Device ID: 0x0a2e 15:29:45 INFO - Revision ID: 0x0009 15:29:45 INFO - Displays: 15:29:45 INFO - Display: 15:29:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:29:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:29:45 INFO - Display Serial Number: 1600x1200 60 15:29:45 INFO - Main Display: Yes 15:29:45 INFO - Mirror: Off 15:29:45 INFO - Online: Yes 15:29:45 INFO - Rotation: Supported 15:29:45 INFO - Return code: 0 15:29:45 INFO - Running post-action listener: _resource_record_post_action 15:29:45 INFO - [mozharness: 2016-09-12 22:29:45.295238Z] Finished run-tests step (success) 15:29:45 INFO - Running post-run listener: _resource_record_post_run 15:29:45 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 36709376; Write bytes: 708361216; Read time: 242; Write time: 2054 15:29:45 INFO - TinderboxPrint: CPU usage
54.9% 15:29:45 INFO - TinderboxPrint: I/O read bytes / time
36,709,376 / 242 15:29:45 INFO - TinderboxPrint: I/O write bytes / time
708,361,216 / 2,054 15:29:45 INFO - TinderboxPrint: CPU idle
2,178.0 (45.1%) 15:29:45 INFO - TinderboxPrint: CPU system
985.4 (20.4%) 15:29:45 INFO - TinderboxPrint: CPU user
1,670.3 (34.6%) 15:29:45 INFO - TinderboxPrint: Swap in / out
442,208,256 / 0 15:29:45 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 393480704; Write bytes: 381798400; Read time: 29223; Write time: 827 15:29:45 INFO - run-tests - Wall time: 1174s; CPU: 55.0%; Read bytes: 30448640; Write bytes: 300295168; Read time: 180; Write time: 1164 15:29:45 INFO - Running post-run listener: _upload_blobber_files 15:29:45 INFO - Blob upload gear active. 15:29:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:29:45 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 15:29:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:29:46 INFO - (blobuploader) - INFO - Open directory for files ... 15:29:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:29:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:29:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:29:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:29:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:29:46 INFO - (blobuploader) - INFO - Done attempting. 15:29:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:29:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:29:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:29:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:29:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:29:48 INFO - (blobuploader) - INFO - Done attempting. 15:29:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:29:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:29:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:29:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:29:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:29:50 INFO - (blobuploader) - INFO - Done attempting. 15:29:50 INFO - (blobuploader) - INFO - Iteration through files over. 15:29:50 INFO - Return code: 0 15:29:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:29:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:29:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/53c017dac0e4ef87f14e5c5dd2186c9cfbb352f8d645934d89375cb656d669fca0d284cb28c0eb48bc19ef06b1dd22441ca2bad1ec81e2194c1a9c78e48c1ef2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/889325aba60ce7faa6b681be542f3a41d0bdfa017bdc9849645cdaff6bee58d748c82df78b122a67bf6163af3edc97ceb4db796175f0f4e528d717ce85036be9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/609ebfabdf2426531faf5a1a3d2ae252d0c2d46f5d6669219a143fcd7aa81eeccaf456a4f465d255f795fae08b18abf63dbb5a400bb667ce1507971769116a32"} 15:29:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:29:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:29:50 INFO - Contents: 15:29:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/53c017dac0e4ef87f14e5c5dd2186c9cfbb352f8d645934d89375cb656d669fca0d284cb28c0eb48bc19ef06b1dd22441ca2bad1ec81e2194c1a9c78e48c1ef2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/889325aba60ce7faa6b681be542f3a41d0bdfa017bdc9849645cdaff6bee58d748c82df78b122a67bf6163af3edc97ceb4db796175f0f4e528d717ce85036be9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/609ebfabdf2426531faf5a1a3d2ae252d0c2d46f5d6669219a143fcd7aa81eeccaf456a4f465d255f795fae08b18abf63dbb5a400bb667ce1507971769116a32"} 15:29:50 INFO - Running post-run listener: copy_logs_to_upload_dir 15:29:50 INFO - Copying logs to upload dir... 15:29:50 INFO - mkdir: /builds/slave/test/build/upload/logs 15:29:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.725125 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-09-12 15:29:50.485614) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 15:29:50.491926) ========= 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.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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/Autoland/sha512/53c017dac0e4ef87f14e5c5dd2186c9cfbb352f8d645934d89375cb656d669fca0d284cb28c0eb48bc19ef06b1dd22441ca2bad1ec81e2194c1a9c78e48c1ef2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/889325aba60ce7faa6b681be542f3a41d0bdfa017bdc9849645cdaff6bee58d748c82df78b122a67bf6163af3edc97ceb4db796175f0f4e528d717ce85036be9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/609ebfabdf2426531faf5a1a3d2ae252d0c2d46f5d6669219a143fcd7aa81eeccaf456a4f465d255f795fae08b18abf63dbb5a400bb667ce1507971769116a32"} build_url:https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011158 build_url: 'https://queue.taskcluster.net/v1/task/DooHwoKjRo-5r4d7dedMrQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/53c017dac0e4ef87f14e5c5dd2186c9cfbb352f8d645934d89375cb656d669fca0d284cb28c0eb48bc19ef06b1dd22441ca2bad1ec81e2194c1a9c78e48c1ef2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/889325aba60ce7faa6b681be542f3a41d0bdfa017bdc9849645cdaff6bee58d748c82df78b122a67bf6163af3edc97ceb4db796175f0f4e528d717ce85036be9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/609ebfabdf2426531faf5a1a3d2ae252d0c2d46f5d6669219a143fcd7aa81eeccaf456a4f465d255f795fae08b18abf63dbb5a400bb667ce1507971769116a32"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 15:29:50.529221) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 15:29:50.529613) ========= 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.cWPlHB7Qb6/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.F0wYuLKZiS/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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.005277 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 15:29:50.579280) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 15:29:50.579659) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 15:29:50.580035) ========= ========= Total master_lag: 0.18 =========