builder: jamun_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0063 starttime: 1495522486.76 results: success (0) buildid: 20170522213024 builduid: 893ffc8c9587498b895a8a472db2058e revision: d2ad5f35644d58d6af38747d6c18b4e1449ea591 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 23:54:46.758386) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 23:54:46.759121) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 23:54:46.759560) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 23:54:46.785551) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 23:54:46.785958) ========= 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.ntl8dknAqx/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.cvRki42vIU/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 --2017-05-22 23:54:46-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 145M=0s 2017-05-22 23:54:46 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141928 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 23:54:46.952550) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 23:54:46.953037) ========= 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.ntl8dknAqx/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.cvRki42vIU/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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.028157 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 23:54:47.002297) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 23:54:47.002892) ========= bash -c 'python archiver_client.py mozharness --repo projects/jamun --rev d2ad5f35644d58d6af38747d6c18b4e1449ea591 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/jamun --rev d2ad5f35644d58d6af38747d6c18b4e1449ea591 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ntl8dknAqx/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.cvRki42vIU/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 2017-05-22 23:54:47,089 truncating revision to first 12 chars 2017-05-22 23:54:47,089 Setting DEBUG logging. 2017-05-22 23:54:47,089 attempt 1/10 2017-05-22 23:54:47,090 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/jamun/d2ad5f35644d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 23:54:48,613 unpacking tar archive at: jamun-d2ad5f35644d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.789413 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 23:54:48.816024) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 23:54:48.816404) ========= script_repo_revision: d2ad5f35644d58d6af38747d6c18b4e1449ea591 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 23:54:48.817001) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 23:54:48.817407) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 23:54:48.836305) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 22 secs) (at 2017-05-22 23:54:48.836870) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Jamun --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Jamun in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Jamun', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Jamun'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ntl8dknAqx/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.cvRki42vIU/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 23:54:49 INFO - MultiFileLogger online at 20170522 23:54:49 in /builds/slave/test 23:54:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Jamun --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Jamun 23:54:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:54:49 INFO - {'append_to_log': False, 23:54:49 INFO - 'base_work_dir': '/builds/slave/test', 23:54:49 INFO - 'blob_upload_branch': 'Jamun', 23:54:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:54:49 INFO - 'branch': 'Jamun', 23:54:49 INFO - 'buildbot_json_path': 'buildprops.json', 23:54:49 INFO - 'config_files': ('talos/mac_config.py',), 23:54:49 INFO - 'default_actions': ('clobber', 23:54:49 INFO - 'read-buildbot-config', 23:54:49 INFO - 'download-and-extract', 23:54:49 INFO - 'populate-webroot', 23:54:49 INFO - 'create-virtualenv', 23:54:49 INFO - 'install', 23:54:49 INFO - 'run-tests'), 23:54:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:54:49 INFO - 'download_minidump_stackwalk': True, 23:54:49 INFO - 'download_symbols': 'ondemand', 23:54:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:54:49 INFO - 'tooltool.py': '/tools/tooltool.py', 23:54:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:54:49 INFO - '/tools/misc-python/virtualenv.py')}, 23:54:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:54:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:54:49 INFO - 'gecko_profile': False, 23:54:49 INFO - 'gecko_profile_interval': 0, 23:54:49 INFO - 'installer_path': 'installer.exe', 23:54:49 INFO - 'log_level': 'info', 23:54:49 INFO - 'log_name': 'talos', 23:54:49 INFO - 'log_to_console': True, 23:54:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:54:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:54:49 INFO - 'opt_config_files': (), 23:54:49 INFO - 'pip_index': False, 23:54:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:54:49 INFO - 'cmd': ('bash', 23:54:49 INFO - '-c', 23:54:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:54:49 INFO - 'enabled': True, 23:54:49 INFO - 'halt_on_failure': False, 23:54:49 INFO - 'name': 'check_screen_resolution'},), 23:54:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:54:49 INFO - 'cmd': ('bash', 23:54:49 INFO - '-c', 23:54:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:54:49 INFO - 'enabled': True, 23:54:49 INFO - 'halt_on_failure': False, 23:54:49 INFO - 'name': 'check_screen_resolution'},), 23:54:49 INFO - 'run_cmd_checks_enabled': True, 23:54:49 INFO - 'suite': 'dromaeojs-e10s', 23:54:49 INFO - 'system_bits': '32', 23:54:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:54:49 INFO - 'title': 't-yosemite-r7-0063', 23:54:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:54:49 INFO - 'use_talos_json': True, 23:54:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:54:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:54:49 INFO - 'work_dir': 'build'} 23:54:49 INFO - [mozharness: 2017-05-23 06:54:49.038056Z] Running clobber step. 23:54:49 INFO - Running pre-action listener: _resource_record_pre_action 23:54:49 INFO - Running main action method: clobber 23:54:49 INFO - rmtree: /builds/slave/test/build 23:54:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:54:51 INFO - Running post-action listener: _resource_record_post_action 23:54:51 INFO - [mozharness: 2017-05-23 06:54:51.558897Z] Finished clobber step (success) 23:54:51 INFO - [mozharness: 2017-05-23 06:54:51.559062Z] Running read-buildbot-config step. 23:54:51 INFO - Running pre-action listener: _resource_record_pre_action 23:54:51 INFO - Running main action method: read_buildbot_config 23:54:51 INFO - Using buildbot properties: 23:54:51 INFO - { 23:54:51 INFO - "project": "", 23:54:51 INFO - "product": "firefox", 23:54:51 INFO - "script_repo_revision": "production", 23:54:51 INFO - "builddir": "jamun_yosemite_r7_devedition_test-dromaeojs-e10s", 23:54:51 INFO - "repository": "", 23:54:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition jamun talos dromaeojs-e10s", 23:54:51 INFO - "buildid": "20170522213024", 23:54:51 INFO - "pgo_build": "False", 23:54:51 INFO - "basedir": "/builds/slave/test", 23:54:51 INFO - "buildnumber": 0, 23:54:51 INFO - "slavename": "t-yosemite-r7-0063", 23:54:51 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 23:54:51 INFO - "platform": "yosemite_r7_devedition", 23:54:51 INFO - "scheduler": "tests-jamun-macosx64-devedition-talos", 23:54:51 INFO - "branch": "jamun", 23:54:51 INFO - "repo_path": "projects/jamun", 23:54:51 INFO - "revision": "d2ad5f35644d58d6af38747d6c18b4e1449ea591", 23:54:51 INFO - "stage_platform": "macosx64-devedition", 23:54:51 INFO - "builduid": "893ffc8c9587498b895a8a472db2058e", 23:54:51 INFO - "slavebuilddir": "test" 23:54:51 INFO - } 23:54:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 23:54:51 INFO - Running post-action listener: _resource_record_post_action 23:54:51 INFO - [mozharness: 2017-05-23 06:54:51.561898Z] Finished read-buildbot-config step (success) 23:54:51 INFO - [mozharness: 2017-05-23 06:54:51.562003Z] Running download-and-extract step. 23:54:51 INFO - Running pre-action listener: _resource_record_pre_action 23:54:51 INFO - Running main action method: download_and_extract 23:54:51 INFO - mkdir: /builds/slave/test/build/tests 23:54:51 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')]} 23:54:51 INFO - trying https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 23:54:51 INFO - Downloading https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 23:54:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 23:54:52 INFO - Downloaded 1287 bytes. 23:54:52 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 23:54:52 INFO - Using the following test package requirements: 23:54:52 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 23:54:52 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 23:54:52 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 23:54:52 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 23:54:52 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 23:54:52 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 23:54:52 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 23:54:52 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 23:54:52 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 23:54:52 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 23:54:52 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 23:54:52 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 23:54:52 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 23:54:52 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 23:54:52 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 23:54:52 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 23:54:52 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 23:54:52 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 23:54:52 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 23:54:52 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 23:54:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 23:54:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 23:54:52 INFO - Fetch https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 23:54:54 INFO - Expected file size: 51797460 23:54:54 INFO - Obtained file size: 51797460 23:55:11 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 23:55:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 23:55:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 23:55:11 INFO - Fetch https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 23:55:12 INFO - Expected file size: 51797460 23:55:12 INFO - Obtained file size: 51797460 23:55:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 23:55:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 23:55:28 INFO - Fetch https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 23:55:29 INFO - Expected file size: 14404449 23:55:29 INFO - Obtained file size: 14404449 23:55:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:55:29 INFO - trying https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 23:55:29 INFO - Downloading https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 23:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 23:55:31 INFO - Downloaded 74766180 bytes. 23:55:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 23:55:31 INFO - mkdir: /builds/slave/test/properties 23:55:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:55:31 INFO - Writing to file /builds/slave/test/properties/build_url 23:55:31 INFO - Contents: 23:55:31 INFO - build_url:https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 23:55:32 INFO - Running post-action listener: _resource_record_post_action 23:55:32 INFO - Running post-action listener: set_extra_try_arguments 23:55:32 INFO - [mozharness: 2017-05-23 06:55:32.038745Z] Finished download-and-extract step (success) 23:55:32 INFO - [mozharness: 2017-05-23 06:55:32.038843Z] Running populate-webroot step. 23:55:32 INFO - Running pre-action listener: _resource_record_pre_action 23:55:32 INFO - Running main action method: populate_webroot 23:55:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:55:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:55:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:55:32 INFO - '--tppagecycles', 23:55:32 INFO - '7'], 23:55:32 INFO - 'tests': ['tsvgm']}}, 23:55:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:55:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:55:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:55:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:55:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:55:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:55:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:55:32 INFO - 'talos_options': ['--disable-e10s'], 23:55:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:55:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:55:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:55:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:55:32 INFO - 'talos_options': ['--disable-e10s'], 23:55:32 INFO - 'tests': ['damp', 'tps']}, 23:55:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:55:32 INFO - 'tests': ['damp', 'tps']}, 23:55:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:55:32 INFO - 'tests': ['dromaeo_dom']}, 23:55:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:55:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:55:32 INFO - 'tests': ['basic_compositor_video']}, 23:55:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:55:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:55:32 INFO - 'tests': ['a11yr', 23:55:32 INFO - 'ts_paint', 23:55:32 INFO - 'tpaint', 23:55:32 INFO - 'sessionrestore', 23:55:32 INFO - 'sessionrestore_no_auto_restore', 23:55:32 INFO - 'tabpaint']}, 23:55:32 INFO - 'other-e10s': {'tests': ['a11yr', 23:55:32 INFO - 'ts_paint', 23:55:32 INFO - 'tpaint', 23:55:32 INFO - 'sessionrestore', 23:55:32 INFO - 'sessionrestore_no_auto_restore', 23:55:32 INFO - 'tabpaint']}, 23:55:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:55:32 INFO - 'ts_paint', 23:55:32 INFO - 'tpaint', 23:55:32 INFO - 'sessionrestore', 23:55:32 INFO - 'sessionrestore_no_auto_restore', 23:55:32 INFO - 'tabpaint']}, 23:55:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:55:32 INFO - 'ts_paint', 23:55:32 INFO - 'tpaint', 23:55:32 INFO - 'sessionrestore', 23:55:32 INFO - 'sessionrestore_no_auto_restore', 23:55:32 INFO - 'tabpaint']}, 23:55:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:55:32 INFO - 'tests': ['a11yr', 23:55:32 INFO - 'ts_paint', 23:55:32 INFO - 'tpaint', 23:55:32 INFO - 'sessionrestore', 23:55:32 INFO - 'sessionrestore_no_auto_restore', 23:55:32 INFO - 'tabpaint']}, 23:55:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:55:32 INFO - 'tests': ['a11yr', 23:55:32 INFO - 'ts_paint', 23:55:32 INFO - 'tpaint', 23:55:32 INFO - 'sessionrestore', 23:55:32 INFO - 'sessionrestore_no_auto_restore', 23:55:32 INFO - 'tabpaint']}, 23:55:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:55:32 INFO - 'tests': ['tsvgx', 23:55:32 INFO - 'tsvgr_opacity', 23:55:32 INFO - 'tart', 23:55:32 INFO - 'tscrollx', 23:55:32 INFO - 'cart', 23:55:32 INFO - 'tsvg_static']}, 23:55:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:55:32 INFO - 'tsvgr_opacity', 23:55:32 INFO - 'tart', 23:55:32 INFO - 'tscrollx', 23:55:32 INFO - 'cart', 23:55:32 INFO - 'tsvg_static']}, 23:55:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:55:32 INFO - 'talos_options': ['--disable-e10s'], 23:55:32 INFO - 'tests': ['tp5o']}, 23:55:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:55:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:55:32 INFO - 'talos_options': ['--disable-e10s', 23:55:32 INFO - '--xperf_path', 23:55:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:55:32 INFO - 'tests': ['tp5n']}, 23:55:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:55:32 INFO - 'talos_options': ['--xperf_path', 23:55:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:55:32 INFO - 'tests': ['tp5n']}}, 23:55:32 INFO - 'talos.zip': {'path': '', 23:55:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:55:32 INFO - Running post-action listener: _resource_record_post_action 23:55:32 INFO - [mozharness: 2017-05-23 06:55:32.057459Z] Finished populate-webroot step (success) 23:55:32 INFO - [mozharness: 2017-05-23 06:55:32.057564Z] Running create-virtualenv step. 23:55:32 INFO - Running pre-action listener: _resource_record_pre_action 23:55:32 INFO - Running main action method: create_virtualenv 23:55:32 INFO - Creating virtualenv /builds/slave/test/build/venv 23:55:32 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 23:55:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:55:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:55:32 INFO - Using real prefix '/tools/python27' 23:55:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:55:33 INFO - Installing distribute.............................................................................................................................................................................................done. 23:55:35 INFO - Installing pip.................done. 23:55:35 INFO - Return code: 0 23:55:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:55:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:55:36 INFO - Reading from file tmpfile_stdout 23:55:36 INFO - Output received: 23:55:36 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 23:55:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:55:36 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')]} 23:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:55:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:55:36 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')]} 23:55:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:55:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:55:36 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 0x10e007b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e00a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c8d82250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dde5d30>, '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 0x10dd0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dddabe8>, '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.ntl8dknAqx/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.cvRki42vIU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 23:55:36 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 23:55:36 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 23:55:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntl8dknAqx/Render', 23:55:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:55:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:55:36 INFO - 'HOME': '/Users/cltbld', 23:55:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:55:36 INFO - 'LOGNAME': 'cltbld', 23:55:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:55:36 INFO - 'MOZ_NO_REMOTE': '1', 23:55:36 INFO - 'NO_EM_RESTART': '1', 23:55:36 INFO - 'PAGER': '/bin/cat', 23:55:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:55:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:55:36 INFO - 'PWD': '/builds/slave/test', 23:55:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:55:36 INFO - 'SHELL': '/bin/bash', 23:55:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cvRki42vIU/Listeners', 23:55:36 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 23:55:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:55:36 INFO - 'USER': 'cltbld', 23:55:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:55:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:55:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:55:36 INFO - 'XPC_FLAGS': '0x0', 23:55:36 INFO - 'XPC_SERVICE_NAME': '0', 23:55:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:55:36 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 23:55:36 INFO - Cleaning up... 23:55:36 INFO - Return code: 0 23:55:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:55:36 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')]} 23:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:55:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:55:36 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')]} 23:55:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:55:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:55:36 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 0x10e007b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e00a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c8d82250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dde5d30>, '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 0x10dd0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dddabe8>, '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.ntl8dknAqx/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.cvRki42vIU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 23:55:36 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 23:55:36 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 23:55:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntl8dknAqx/Render', 23:55:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:55:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:55:36 INFO - 'HOME': '/Users/cltbld', 23:55:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:55:36 INFO - 'LOGNAME': 'cltbld', 23:55:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:55:36 INFO - 'MOZ_NO_REMOTE': '1', 23:55:36 INFO - 'NO_EM_RESTART': '1', 23:55:36 INFO - 'PAGER': '/bin/cat', 23:55:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:55:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:55:36 INFO - 'PWD': '/builds/slave/test', 23:55:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:55:36 INFO - 'SHELL': '/bin/bash', 23:55:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cvRki42vIU/Listeners', 23:55:36 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 23:55:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:55:36 INFO - 'USER': 'cltbld', 23:55:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:55:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:55:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:55:36 INFO - 'XPC_FLAGS': '0x0', 23:55:36 INFO - 'XPC_SERVICE_NAME': '0', 23:55:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:55:36 INFO - Downloading/unpacking psutil>=3.1.1 23:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:55:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:55:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:55:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:55:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:58:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:58:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:58:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:58:09 INFO - Installing collected packages: psutil 23:58:09 INFO - Running setup.py install for psutil 23:58:09 INFO - building 'psutil._psutil_osx' extension 23:58:09 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 23:58:09 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 23:58:09 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 23:58:09 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 23:58:09 INFO - building 'psutil._psutil_posix' extension 23:58:09 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 23:58:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:58:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:58:10 INFO - ^ 23:58:10 INFO - 1 warning generated. 23:58:10 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 23:58:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:58:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:58:10 INFO - Successfully installed psutil 23:58:10 INFO - Cleaning up... 23:58:10 INFO - Return code: 0 23:58:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:58:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:58:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:58:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:58:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:58:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:58:10 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 0x10e007b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e00a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c8d82250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dde5d30>, '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 0x10dd0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dddabe8>, '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.ntl8dknAqx/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.cvRki42vIU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 23:58:10 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 23:58:10 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 23:58:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntl8dknAqx/Render', 23:58:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:58:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:58:10 INFO - 'HOME': '/Users/cltbld', 23:58:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:58:10 INFO - 'LOGNAME': 'cltbld', 23:58:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:58:10 INFO - 'MOZ_NO_REMOTE': '1', 23:58:10 INFO - 'NO_EM_RESTART': '1', 23:58:10 INFO - 'PAGER': '/bin/cat', 23:58:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:58:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:58:10 INFO - 'PWD': '/builds/slave/test', 23:58:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:58:10 INFO - 'SHELL': '/bin/bash', 23:58:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cvRki42vIU/Listeners', 23:58:10 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 23:58:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:58:10 INFO - 'USER': 'cltbld', 23:58:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:58:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:58:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:58:10 INFO - 'XPC_FLAGS': '0x0', 23:58:10 INFO - 'XPC_SERVICE_NAME': '0', 23:58:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:58:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:58:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:58:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:58:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:58:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:58:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:00:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:00:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:00:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:00:43 INFO - Installing collected packages: mozsystemmonitor 00:00:43 INFO - Running setup.py install for mozsystemmonitor 00:00:43 INFO - Successfully installed mozsystemmonitor 00:00:43 INFO - Cleaning up... 00:00:43 INFO - Return code: 0 00:00:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:00:43 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')]} 00:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:00:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:00:43 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')]} 00:00:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:00:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:00:45 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 0x10e007b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e00a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c8d82250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dde5d30>, '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 0x10dd0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dddabe8>, '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.ntl8dknAqx/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.cvRki42vIU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 00:00:45 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 00:00:45 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 00:00:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntl8dknAqx/Render', 00:00:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:00:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:00:45 INFO - 'HOME': '/Users/cltbld', 00:00:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:00:45 INFO - 'LOGNAME': 'cltbld', 00:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:00:45 INFO - 'MOZ_NO_REMOTE': '1', 00:00:45 INFO - 'NO_EM_RESTART': '1', 00:00:45 INFO - 'PAGER': '/bin/cat', 00:00:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:00:45 INFO - 'PWD': '/builds/slave/test', 00:00:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:00:45 INFO - 'SHELL': '/bin/bash', 00:00:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cvRki42vIU/Listeners', 00:00:45 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 00:00:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:00:45 INFO - 'USER': 'cltbld', 00:00:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:00:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:00:45 INFO - 'XPC_FLAGS': '0x0', 00:00:45 INFO - 'XPC_SERVICE_NAME': '0', 00:00:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:00:45 INFO - Downloading/unpacking jsonschema==2.5.1 00:00:45 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 00:00:45 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 00:00:45 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 00:00:45 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 00:00:45 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 00:00:45 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 00:03:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:03:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:03: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 00:03: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 00:03: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 00:03: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 00:03: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 00:03: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 00:05:47 INFO - Downloading functools32-3.2.3-2.tar.gz 00:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:05:48 INFO - warning: no files found matching '*.txt' 00:05:48 INFO - no previously-included directories found matching 'build' 00:05:48 INFO - no previously-included directories found matching 'dist' 00:05:48 INFO - no previously-included directories found matching '.git*' 00:05:48 INFO - Installing collected packages: jsonschema, functools32 00:05:48 INFO - Running setup.py install for functools32 00:05:48 INFO - warning: no files found matching '*.txt' 00:05:48 INFO - no previously-included directories found matching 'build' 00:05:48 INFO - no previously-included directories found matching 'dist' 00:05:48 INFO - no previously-included directories found matching '.git*' 00:05:48 INFO - Successfully installed jsonschema functools32 00:05:48 INFO - Cleaning up... 00:05:48 INFO - Return code: 0 00:05:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:05:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:05:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:05:49 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 0x10e007b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e00a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c8d82250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dde5d30>, '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 0x10dd0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dddabe8>, '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.ntl8dknAqx/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.cvRki42vIU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 00:05:49 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 00:05:49 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 00:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntl8dknAqx/Render', 00:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:05:49 INFO - 'HOME': '/Users/cltbld', 00:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:05:49 INFO - 'LOGNAME': 'cltbld', 00:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:05:49 INFO - 'MOZ_NO_REMOTE': '1', 00:05:49 INFO - 'NO_EM_RESTART': '1', 00:05:49 INFO - 'PAGER': '/bin/cat', 00:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:05:49 INFO - 'PWD': '/builds/slave/test', 00:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:05:49 INFO - 'SHELL': '/bin/bash', 00:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cvRki42vIU/Listeners', 00:05:49 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 00:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:05:49 INFO - 'USER': 'cltbld', 00:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:05:49 INFO - 'XPC_FLAGS': '0x0', 00:05:49 INFO - 'XPC_SERVICE_NAME': '0', 00:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:05:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:05:50 INFO - Cleaning up... 00:05:50 INFO - Return code: 0 00:05:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:05:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:05:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:05:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:05:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:05:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:05:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e007b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e00a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c8d82250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dde5d30>, '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 0x10dd0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dddabe8>, '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.ntl8dknAqx/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.cvRki42vIU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 00:05:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 00:05:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 00:05:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntl8dknAqx/Render', 00:05:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:05:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:05:50 INFO - 'HOME': '/Users/cltbld', 00:05:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:05:50 INFO - 'LOGNAME': 'cltbld', 00:05:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:05:50 INFO - 'MOZ_NO_REMOTE': '1', 00:05:50 INFO - 'NO_EM_RESTART': '1', 00:05:50 INFO - 'PAGER': '/bin/cat', 00:05:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:05:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:05:50 INFO - 'PWD': '/builds/slave/test', 00:05:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:05:50 INFO - 'SHELL': '/bin/bash', 00:05:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cvRki42vIU/Listeners', 00:05:50 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 00:05:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:05:50 INFO - 'USER': 'cltbld', 00:05:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:05:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:05:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:05:50 INFO - 'XPC_FLAGS': '0x0', 00:05:50 INFO - 'XPC_SERVICE_NAME': '0', 00:05:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:05:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:05:50 INFO - Downloading/unpacking blobuploader==1.2.4 00:05:50 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 00:05:50 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 00:05:50 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 00:05:50 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 00:05:50 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 00:05:50 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 00:08:22 INFO - Downloading blobuploader-1.2.4.tar.gz 00:08:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:08:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:08:22 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 00:08:22 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 00:08:22 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 00:08:22 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 00:08:22 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 00:08:22 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 00:10:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:10:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:10:53 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 00:10:53 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 00:10:53 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 00:10:53 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 00:10:53 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 00:10:53 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 00:13:24 INFO - Downloading docopt-0.6.1.tar.gz 00:13:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:13:24 INFO - Installing collected packages: blobuploader, requests, docopt 00:13:24 INFO - Running setup.py install for blobuploader 00:13:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:13:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:13:24 INFO - Running setup.py install for requests 00:13:25 INFO - Running setup.py install for docopt 00:13:25 INFO - Successfully installed blobuploader requests docopt 00:13:25 INFO - Cleaning up... 00:13:25 INFO - Return code: 0 00:13:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:13: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')]} 00:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:13:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:13: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')]} 00:13:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:13:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:13:26 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 0x10e007b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e00a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c8d82250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dde5d30>, '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 0x10dd0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dddabe8>, '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.ntl8dknAqx/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.cvRki42vIU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 00:13:26 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 00:13:26 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 00:13:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntl8dknAqx/Render', 00:13:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:13:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:13:26 INFO - 'HOME': '/Users/cltbld', 00:13:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:13:26 INFO - 'LOGNAME': 'cltbld', 00:13:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:13:26 INFO - 'MOZ_NO_REMOTE': '1', 00:13:26 INFO - 'NO_EM_RESTART': '1', 00:13:26 INFO - 'PAGER': '/bin/cat', 00:13:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:13:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:13:26 INFO - 'PWD': '/builds/slave/test', 00:13:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:13:26 INFO - 'SHELL': '/bin/bash', 00:13:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cvRki42vIU/Listeners', 00:13:26 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 00:13:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:13:26 INFO - 'USER': 'cltbld', 00:13:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:13:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:13:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:13:26 INFO - 'XPC_FLAGS': '0x0', 00:13:26 INFO - 'XPC_SERVICE_NAME': '0', 00:13:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:13:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:13:26 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-7mGiNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:13:26 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-BARTnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:13:26 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-beoDXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:13:26 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-vQjUz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:13:26 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-88vCf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:13:26 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-FrRUIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:13:27 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-hE6iO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:13:27 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-FiGoeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:13:27 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-ljHYoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:13:27 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-tJwOav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:13:27 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-pMBllX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:13:27 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-dnLQZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:13:27 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-QbvaDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:13:27 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-pR3wyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:13:27 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-K7fiZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:13:28 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-4VOzoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:13:28 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-ug9IJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:13:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:13:28 INFO - Running setup.py install for manifestparser 00:13:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:13:28 INFO - Running setup.py install for mozcrash 00:13:28 INFO - Running setup.py install for mozdebug 00:13:28 INFO - Running setup.py install for mozdevice 00:13:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:13:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:13:28 INFO - Running setup.py install for mozfile 00:13:28 INFO - Running setup.py install for mozhttpd 00:13:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:13:29 INFO - Running setup.py install for mozinfo 00:13:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:13:29 INFO - Running setup.py install for mozInstall 00:13:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:13:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:13:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:13:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:13:29 INFO - Running setup.py install for mozleak 00:13:29 INFO - Running setup.py install for mozlog 00:13:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:13:29 INFO - Running setup.py install for moznetwork 00:13:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:13:29 INFO - Running setup.py install for mozprocess 00:13:30 INFO - Running setup.py install for mozprofile 00:13:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:13:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:13:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:13:30 INFO - Running setup.py install for mozrunner 00:13:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:13:30 INFO - Running setup.py install for mozscreenshot 00:13:30 INFO - Running setup.py install for moztest 00:13:30 INFO - Running setup.py install for mozversion 00:13:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:13:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:13:30 INFO - Cleaning up... 00:13:30 INFO - Return code: 0 00:13:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:13: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')]} 00:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:13:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:13: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')]} 00:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:13:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:13: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 0x10e007b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e00a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c8d82250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dde5d30>, '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 0x10dd0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dddabe8>, '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.ntl8dknAqx/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.cvRki42vIU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 00:13: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 00:13: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 00:13:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntl8dknAqx/Render', 00:13:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:13:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:13:30 INFO - 'HOME': '/Users/cltbld', 00:13:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:13:30 INFO - 'LOGNAME': 'cltbld', 00:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:13:30 INFO - 'MOZ_NO_REMOTE': '1', 00:13:30 INFO - 'NO_EM_RESTART': '1', 00:13:30 INFO - 'PAGER': '/bin/cat', 00:13:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:13:30 INFO - 'PWD': '/builds/slave/test', 00:13:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:13:30 INFO - 'SHELL': '/bin/bash', 00:13:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cvRki42vIU/Listeners', 00:13:30 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 00:13:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:13:30 INFO - 'USER': 'cltbld', 00:13:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:13:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:13:30 INFO - 'XPC_FLAGS': '0x0', 00:13:30 INFO - 'XPC_SERVICE_NAME': '0', 00:13:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:13:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:13:31 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-_2Jtcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:13:31 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)) 00:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:13:31 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-Rz3Ou1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:13:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 00:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:13:31 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-E0ZYw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:13: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)) 00:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:13:31 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-rMr6eT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:13:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 00:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:13:31 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-SlJGyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:13: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)) 00:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:13:31 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-oWH6bX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:13: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)) 00:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:13:31 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-Vs3FSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:13: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)) 00:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:13:31 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-UdjT7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:13: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)) 00:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:13:31 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-uCB_tX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:13:32 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)) 00:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:13:32 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-dQtPys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:13:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 00:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:13:32 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-OH4gyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:13:32 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)) 00:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:13:32 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-DSZAy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:13:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 00:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:13:32 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-Cw8TZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:13: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)) 00:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:13:32 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-vZfNnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:13:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 00:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:13:32 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-o76nf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:13: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)) 00:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:13:32 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-0wjovk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:13:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 00:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:13:32 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-gjGoWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:13: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)) 00:13: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==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:13: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==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:13: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)) 00:13: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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:13: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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:13:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:13: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 00:13: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 00:13: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 00:13: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 00:13: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 00:13: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 00:16:05 INFO - Downloading blessings-1.6.tar.gz 00:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:16:05 INFO - Installing collected packages: blessings 00:16:05 INFO - Running setup.py install for blessings 00:16:05 INFO - Successfully installed blessings 00:16:05 INFO - Cleaning up... 00:16:05 INFO - Return code: 0 00:16:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:16:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:16:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:16:05 INFO - Reading from file tmpfile_stdout 00:16:05 INFO - Current package versions: 00:16:05 INFO - blessings == 1.6 00:16:05 INFO - blobuploader == 1.2.4 00:16:05 INFO - docopt == 0.6.1 00:16:05 INFO - functools32 == 3.2.3-2 00:16:05 INFO - jsonschema == 2.5.1 00:16:05 INFO - manifestparser == 1.1 00:16:05 INFO - mozInstall == 1.12 00:16:05 INFO - mozcrash == 1.0 00:16:05 INFO - mozdebug == 0.1 00:16:05 INFO - mozdevice == 0.49 00:16:05 INFO - mozfile == 1.2 00:16:05 INFO - mozhttpd == 0.7 00:16:05 INFO - mozinfo == 0.9 00:16:05 INFO - mozleak == 0.1 00:16:05 INFO - mozlog == 3.4 00:16:05 INFO - moznetwork == 0.27 00:16:05 INFO - mozprocess == 0.25 00:16:05 INFO - mozprofile == 0.28 00:16:05 INFO - mozrunner == 6.13 00:16:05 INFO - mozscreenshot == 0.1 00:16:05 INFO - mozsystemmonitor == 0.3 00:16:05 INFO - moztest == 0.8 00:16:05 INFO - mozversion == 1.4 00:16:05 INFO - psutil == 3.1.1 00:16:05 INFO - requests == 1.2.3 00:16:05 INFO - wsgiref == 0.1.2 00:16:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:16:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:16:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:07 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 0x10e007b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e00a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c8d82250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dde5d30>, '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 0x10dd0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dddabe8>, '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.ntl8dknAqx/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.cvRki42vIU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 00:16:07 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 00:16:07 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 00:16:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntl8dknAqx/Render', 00:16:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:16:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:16:07 INFO - 'HOME': '/Users/cltbld', 00:16:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:16:07 INFO - 'LOGNAME': 'cltbld', 00:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:16:07 INFO - 'MOZ_NO_REMOTE': '1', 00:16:07 INFO - 'NO_EM_RESTART': '1', 00:16:07 INFO - 'PAGER': '/bin/cat', 00:16:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:16:07 INFO - 'PWD': '/builds/slave/test', 00:16:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:16:07 INFO - 'SHELL': '/bin/bash', 00:16:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cvRki42vIU/Listeners', 00:16:07 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 00:16:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:16:07 INFO - 'USER': 'cltbld', 00:16:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:16:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:16:07 INFO - 'XPC_FLAGS': '0x0', 00:16:07 INFO - 'XPC_SERVICE_NAME': '0', 00:16:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:16:07 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)) 00:16:07 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)) 00:16:07 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)) 00:16:07 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)) 00:16:07 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)) 00:16:07 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)) 00:16:07 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)) 00:16:07 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)) 00:16:07 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)) 00:16:07 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)) 00:16:07 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)) 00:16:07 INFO - Cleaning up... 00:16:07 INFO - Return code: 0 00:16:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:16:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:16:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:16:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e007b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e00a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c8d82250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dde5d30>, '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 0x10dd0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dddabe8>, '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.ntl8dknAqx/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.cvRki42vIU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 00:16:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 00:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 00:16:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntl8dknAqx/Render', 00:16:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:16:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:16:07 INFO - 'HOME': '/Users/cltbld', 00:16:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:16:07 INFO - 'LOGNAME': 'cltbld', 00:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:16:07 INFO - 'MOZ_NO_REMOTE': '1', 00:16:07 INFO - 'NO_EM_RESTART': '1', 00:16:07 INFO - 'PAGER': '/bin/cat', 00:16:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:16:07 INFO - 'PWD': '/builds/slave/test', 00:16:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:16:07 INFO - 'SHELL': '/bin/bash', 00:16:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cvRki42vIU/Listeners', 00:16:07 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 00:16:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:16:07 INFO - 'USER': 'cltbld', 00:16:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:16:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:16:07 INFO - 'XPC_FLAGS': '0x0', 00:16:07 INFO - 'XPC_SERVICE_NAME': '0', 00:16:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:16:07 INFO - Cleaning up... 00:16:07 INFO - Return code: 0 00:16:07 INFO - Running post-action listener: _resource_record_post_action 00:16:07 INFO - Running post-action listener: _start_resource_monitoring 00:16:07 INFO - Starting resource monitoring. 00:16:07 INFO - [mozharness: 2017-05-23 07:16:07.611756Z] Finished create-virtualenv step (success) 00:16:07 INFO - [mozharness: 2017-05-23 07:16:07.612719Z] Running install step. 00:16:07 INFO - Running pre-action listener: _resource_record_pre_action 00:16:07 INFO - Running main action method: install 00:16:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:16:07 INFO - Reading from file tmpfile_stdout 00:16:07 INFO - Detecting whether we're running mozinstall >=1.0... 00:16:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:16:07 INFO - Reading from file tmpfile_stdout 00:16:07 INFO - Output received: 00:16:07 INFO - Usage: mozinstall [options] installer 00:16:07 INFO - Options: 00:16:07 INFO - -h, --help show this help message and exit 00:16:07 INFO - -d DEST, --destination=DEST 00:16:07 INFO - Directory to install application into. [default: 00:16:07 INFO - "/builds/slave/test"] 00:16:07 INFO - --app=APP Application being installed. [default: firefox] 00:16:07 INFO - mkdir: /builds/slave/test/build/application 00:16:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 00:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 00:16:27 INFO - Reading from file tmpfile_stdout 00:16:27 INFO - Output received: 00:16:27 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 00:16:27 INFO - Running post-action listener: _resource_record_post_action 00:16:27 INFO - [mozharness: 2017-05-23 07:16:27.746845Z] Finished install step (success) 00:16:27 INFO - [mozharness: 2017-05-23 07:16:27.746993Z] Running run-tests step. 00:16:27 INFO - Running pre-action listener: _resource_record_pre_action 00:16:27 INFO - Running main action method: run_tests 00:16:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:16:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:16:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:16:27 INFO - 2017-05-23 00:16:27.761 screenresolution[1776:8975] starting screenresolution argv=screenresolution get 00:16:27 INFO - 2017-05-23 00:16:27.764 screenresolution[1776:8975] Display 0: 1600x1200x32@60 00:16:27 INFO - 2017-05-23 00:16:27.770 screenresolution[1777:8977] starting screenresolution argv=screenresolution list 00:16:27 INFO - Available Modes on Display 0 00:16:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:16:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:16:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:16:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:16:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:16:27 INFO - Intel Iris: 00:16:27 INFO - Chipset Model: Intel Iris 00:16:27 INFO - Type: GPU 00:16:27 INFO - Bus: Built-In 00:16:28 INFO - VRAM (Dynamic, Max): 1536 MB 00:16:28 INFO - Vendor: Intel (0x8086) 00:16:28 INFO - Device ID: 0x0a2e 00:16:28 INFO - Revision ID: 0x0009 00:16:28 INFO - Displays: 00:16:28 INFO - Display: 00:16:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:16:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:16:28 INFO - Display Serial Number: 1600x1200 60 00:16:28 INFO - Main Display: Yes 00:16:28 INFO - Mirror: Off 00:16:28 INFO - Online: Yes 00:16:28 INFO - Rotation: Supported 00:16:28 INFO - Return code: 0 00:16:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:16:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:16:28 INFO - Python 2.7.3 00:16:28 INFO - Return code: 0 00:16:28 INFO - grabbing minidump binary from tooltool 00:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:16:28 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 0x10dde5d30>, '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 0x10dd0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dddabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:16:28 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 00:16:28 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 00:16:28 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 00:16:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:16:28 INFO - Return code: 0 00:16:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:16:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:16:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:16:28 INFO - ENV: RUST_BACKTRACE is now 1 00:16:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:16:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:16:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Jamun', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0063', '--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 00:16:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Jamun --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0063 --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 00:16:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntl8dknAqx/Render', 00:16:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:16:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:16:28 INFO - 'HOME': '/Users/cltbld', 00:16:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:16:28 INFO - 'LOGNAME': 'cltbld', 00:16:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:16:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:16:28 INFO - 'MOZ_NO_REMOTE': '1', 00:16:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:16:28 INFO - 'NO_EM_RESTART': '1', 00:16:28 INFO - 'PAGER': '/bin/cat', 00:16:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:16:28 INFO - 'PWD': '/builds/slave/test', 00:16:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:16:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:16:28 INFO - 'RUST_BACKTRACE': '1', 00:16:28 INFO - 'SHELL': '/bin/bash', 00:16:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cvRki42vIU/Listeners', 00:16:28 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 00:16:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:16:28 INFO - 'USER': 'cltbld', 00:16:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:16:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:16:28 INFO - 'XPC_FLAGS': '0x0', 00:16:28 INFO - 'XPC_SERVICE_NAME': '0', 00:16:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:16:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Jamun', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0063', '--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 00:16:28 INFO - mozversion application_buildid: 20170522213024 00:16:28 INFO - mozversion application_changeset: d2ad5f35644d58d6af38747d6c18b4e1449ea591 00:16:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 00:16:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:16:28 INFO - mozversion application_name: Firefox 00:16:28 INFO - mozversion application_remotingname: firefox-dev 00:16:28 INFO - mozversion application_repository: https://hg.mozilla.org/projects/jamun 00:16:28 INFO - mozversion application_vendor: Mozilla 00:16:28 INFO - mozversion application_version: 54.0 00:16:28 INFO - mozversion platform_buildid: 20170522213024 00:16:28 INFO - mozversion platform_changeset: d2ad5f35644d58d6af38747d6c18b4e1449ea591 00:16:28 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/jamun 00:16:28 INFO - mozversion platform_version: 54.0 00:16:28 INFO - using testdate: 1495523788 00:16:28 INFO - actual date: 1495523788 00:16:28 INFO - starting webserver on 'localhost:49249' 00:16:28 INFO - SUITE-START | Running 2 tests 00:16:28 INFO - TEST-START | dromaeo_css 00:16:28 INFO - operating with platform_type : mac_ 00:16:28 INFO - Initialising browser for dromaeo_css test... 00:16:28 INFO - TEST-INFO | started process 1787 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmpohOzI8/profile http://localhost:49249/getInfo.html) 00:16:31 INFO - PROCESS | 1787 | 2017-05-23 00:16:31.388 plugin-container[1792:9168] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 00:16:31 INFO - PROCESS | 1787 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:16:31 INFO - PROCESS | 1787 | 2017-05-23 00:16:31.389 plugin-container[1792:9168] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9117, name = 'com.apple.CFPasteboardClient' 00:16:31 INFO - PROCESS | 1787 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:16:31 INFO - PROCESS | 1787 | 2017-05-23 00:16:31.389 plugin-container[1792:9168] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:16:31 INFO - PROCESS | 1787 | __metrics Screen width/height:1600/1200 00:16:31 INFO - PROCESS | 1787 | colorDepth:24 00:16:31 INFO - PROCESS | 1787 | Browser inner width/height: 1024/689 00:16:31 INFO - PROCESS | 1787 | __metrics 00:16:31 ERROR - PROCESS | 1787 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 00:16:32 INFO - TEST-INFO | 1787: exit 0 00:16:32 INFO - Browser initialized. 00:16:32 INFO - Running cycle 1/1 for dromaeo_css test... 00:16:32 INFO - TEST-INFO | started process 1794 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmpohOzI8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:16:34 INFO - PROCESS | 1794 | 2017-05-23 00:16:34.430 plugin-container[1795:9299] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x842b, name = 'com.apple.tsm.portname' 00:16:34 INFO - PROCESS | 1794 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:16:34 INFO - PROCESS | 1794 | 2017-05-23 00:16:34.431 plugin-container[1795:9299] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 00:16:34 INFO - PROCESS | 1794 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:16:34 INFO - PROCESS | 1794 | 2017-05-23 00:16:34.431 plugin-container[1795:9299] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:16:44 INFO - PROCESS | 1794 | RSS: Main: 202919936 00:16:44 INFO - PROCESS | 1794 | 00:19:48 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 00:19:49 INFO - PROCESS | 1794 | RSS: Main: 215728128 00:19:49 INFO - PROCESS | 1794 | 00:22:51 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 00:22:52 INFO - PROCESS | 1794 | RSS: Main: 231026688 00:22:52 INFO - PROCESS | 1794 | 00:25:54 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 00:25:55 INFO - PROCESS | 1794 | RSS: Main: 232448000 00:25:55 INFO - PROCESS | 1794 | 00:28:56 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 00:28:57 INFO - PROCESS | 1794 | RSS: Main: 235814912 00:28:57 INFO - PROCESS | 1794 | 00:32:00 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 00:32:00 INFO - PROCESS | 1794 | RSS: Main: 238247936 00:32:00 INFO - PROCESS | 1794 | 00:35:03 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 00:35:04 INFO - PROCESS | 1794 | RSS: Main: 237314048 00:35:04 INFO - PROCESS | 1794 | 00:35:04 INFO - PROCESS | 1794 | __start_tp_report 00:35:04 INFO - PROCESS | 1794 | _x_x_mozilla_page_load 00:35:04 INFO - PROCESS | 1794 | _x_x_mozilla_page_load_details 00:35:04 INFO - PROCESS | 1794 | |i|pagename|runs| 00:35:04 INFO - PROCESS | 1794 | |0;dojo.html;3463.948040779389;3482.4079906415905;3504.2115524007086;3504.386732321843;3517.4547945068507;5983.640981541111;6013.037913933771;6065.636061836281;6112.938870611292;6124.265088189412;5001.8249361272365;6082.878342433149;6112.541559383042;6142.017277235644;6191.442770150686;5423.728813559336;5501.889962200774;5508.173773933902;5523.143912693539;5651.7739290428335;5562.694051827169;5565.1374037024125;5582.49757521823;5595.048841696901;5596.244506991545;5368.087425137716;5386.380566234874;5575.916361254584;5579.13523403871;5587.804426845061;5470.070088085035;5532.806351777688;5534.889302213933;5552.3892371839065;5555.416681248492;5406.162044883049;5513.365962914276;5525.171224316385;5536.806211782568;5543.168524721284;5093.261477085802;5697.914531282034;6120.938790612081;6217.191765070557;6240.968795156041;6205.596636218631;6289.119523266746;6304.27500837405;6401;6403.135576697133;6625.701843417058;6636.568623039487;6637.634930078843;6653.833654158636;6678.09845670838;6173.228346456693;6205.658688772116;6237.563370564017;6278.246610406781;6343.746250150035;6338.366163383625;6440.420362167428;6543.509236807164;6685.79942601713;6755.763548275787;6235.781747638811;6268.2478102627065;6324.683765811691;6329.145565348711;6342.080398342307;6514.413702766774;6518.967405162944;6549.4760419167305;6589.835254118685;6593.86812263752;6180.351063138306;6381.54310590426;6466.224053113657;6470.805875823733;6490.740370385131;6261.624302541862;6308.842278943063;6319.652419116901;6360.9045864312075;6399;5207.270982062439;5218.869528261824;5220.556252718486;5223.268742376071;5245.7114858683135;4593.150267200579;4623.260278355447;4635.956909695292;4643.419572553431;4680.040591678698;5900.468957793819;5947.197128387612;5950.9107363389585;5962.612430192024;6050.848728781815;6447.032657154552;6570.671466426659;6572.80281591553;6573.769918052845;6585.2426970898005;5130.589552835764;5540.7533305006855;5756.712164391847;5894.115882617642;5943.762249509971;4911.263310503453;5661.405552416937;5764.337101233328;5766.654000759968;5774.566907481955;5285.916387140704;6401;6435.4529864962005;6642.169728783902;6792.422644075309;5870.765169393177;6540.738370465128;6546.378094081055;6570.704318305783;6593.241777195492;5623.634463759842;6206.658633775186;6283.622982621057;6307.873842523216;6772.864542709217;6274.6548939808745;6300.401461861115;6332.588381755356;6435.22777266731;6461.321561236003;6494.64279464634;6546.24718157418;6553.442957348679;6564.606123632598;6585.901211481927;5396.325459317585;6188.288346840171;6339.175907132043;6368.808935731936;6381.521385895983;6244.438000579969;6277.497800176066;6290.056491526308;6370.299267080525;6415.679216039272;6147.692615369124;6255.78104766331;6337.4613157882095;6339.587926784744;6366.713497892513;6389.48884089281;6401.64790936503;6428.325025872216;6428.935710642833;6429.58207716497;6137.631742095488;6311.936880631319;6318.778842740482;6328.335524769833;6346.841328966812 00:35:04 INFO - PROCESS | 1794 | |1;ext.html;764.866148424026;810.2140923304396;833.3832963606933;973.8393165127759;973.9853902191464;33948.49077263837;38887.22225555491;39734.403983940225;41363.965900852454;41906.58093419065;40384.59615403845;40517.39223911644;40533.59466405335;40579;40719.59280407195;6917.688704008319;8218.917810821906;10926.68879734433;10933.56265749369;11266.605668801594;9736.610535578604;11108.500117494732;11126.721831954226;11146.386948717818;11167.497462614161;11153.10775137987;11158.218924675277;11182.720431989224;11196.048335891459;11208.439578021107;11121.27711698741;11121.55513779448;11175.329480231172;11189.328640281608;11288.492017859217;10931.945340273247;10981.341119532774;11001.22991390603;11017.338959662446;11020.50407731646;35679;35815.64184358149;35872.461913071325;35997;36482.63517364819;3817.560980487224;3828.846846126152;3843.5964223756664;3865.033741564609;3905.921881562353;25777.35556611081;26636.600450993057;27253.591196132074;27292.863535682394;27326;2568.3964268396912;3153.668864769189;3173.4763763978917;3190.680931906814;3304.289577740797;258900;285046;296203;296279;296490;38552.80723596364;44419.889502762686;44537;44641.10717785691;44681;34933.65066349355;37660;45989;46128.769356153;46133.84665383392;14234.359453824603;14380.56858294253;14396.496122635657;14423.85576144246;14435.133891966516;12482.937585312196;12502.624921252194;12503.937480312723;12520.624381268395;12529.248245105322;11158.386288754034;12248.510059597695;12293.446794894251;12309.199902006341;12402.13185077054;4770.594499467515;4803;5711.543076553866;5747.511461525817;5765.538756899438;1128.2891778179694;1140;1143.857017872766;1159.0785325666116;1216.7870622641003;26964.86517567439;26999.325016874736;27219.319517012234;27329.590056149173;27415.862920685267;1234.3087870792385;1251.2429979862234;1252.9310887901254;1306.1705816806207;1311.9081664283408;11930.642080737418;14052.648683782783;14205.78691319631;14345.569632911029;14377.78433323501;480692;482001;483058;483508;484345;44331.77834110809;44535.10929781386;44638.77680611576;44643;44681.329780053326;1462.817147856518;1476.4906107392933;1510.0335785097545;1513.788069670247;1517.1276516003343;25825.483490329712;26736.732632673426;27235.59146612842;27252.454950900476;27330;47769.28346074742;47916.520834791205;48255.517444825105;48381.27428088511;48694.53916382514;430978;432064;432092;433103;433617;31894.840525797223;32029.359412811147;32108.518372224902;32173.35653286968;32175.839120804245;43733.90665233395;44167.55832441634;45316.32025519554;45555.54444455646;45668.314975274734;30006.849965750032;30066.098017059525;30389.392212156075;30821.69178308278;30885.845570772002;21205;22799.65800513027;29942.700572993992;30747.385052299276;30877.228069298446;36022.4596631056;36197.63802361942;36561.63438365582;36709.63290367062;36830.44754328633;28148.71851281461;29136.29981693062;29665.851670741507;29944.101676949726;31016.534751978288;23604.645930311402;23617.64573531364;23863.761362386154;23989;24035.639465407683 00:35:04 INFO - PROCESS | 1794 | |2;jquery.html;340.63551999360686;400.53737932687744;408.30791807885646;410.16940695092455;456.64381782209887;20506.89746551267;21916.57587237489;22357.664635030465;23146.42133946652;23173.304800856;18187.545311367223;22653.54692906145;22703.956643902045;23008.76991230087;23754.5249095018;6815;6961.060256865309;7010;7087.468439867005;7088.007678924954;5806;5827.562932780037;6584.341565843425;6927.722891084376;6932.202796678396;6704.430123439513;6807.523473356842;6860.279670634562;6922.134733158356;6948.992396102562;6926.896096558556;6942.618156001393;7000.26497217795;7009.509334346598;7030.683619237147;6742.089817874573;6773.3903948644365;6815.693293801791;6822.795316140524;6830.7609233676585;20354;23593.646095308413;25512.872435637888;27106;27630.585541216897;2988.8206707597396;2990.8654110565076;3041.3309072004345;3063.846807659608;3077.476828939086;16789.748153777702;16837.747433788503;17032.978021318762;17094.974301541948;17169.227384767717;2004.859659823813;2018.1221168791624;2020.1919232307048;2027.7465316835396;2061.989690051555;133588;134396;137369;138580.30709846388;138742;19065.90467047656;23014.19450319231;23544.411389715395;24435.511289774462;24465;37370.06574835651;37425.62574374222;37672.43491347632;38114.23771524554;38401;11211.831822522669;11522.135839812048;11531.250468719674;11679;11859.525618975316;9360.017198194228;9374.765630859147;9392.436453812794;9537.523123843781;9555.378900371592;9109.908900910907;9455.29085318604;10928.289661172157;11161.609343673095;11264.267822591506;4052.9664935441865;4446.755428451402;4498.145352383037;4522.366868638327;4524.23088075028;791.6160662078887;802.7757669553977;822.8560001999626;915.514777168102;960.6205548808182;18107.909460452876;22424.260549690774;22742.65886011711;22785.544289114125;22819.657705134436;965.8068386322764;981.3964411886781;1003.2926786615565;1008.8789345278614;1010.0707349238718;11346.886531134583;11886.881131188751;12076.939615301868;12077;12132.575359862363;716791;729250;747943;761543.1922840461;785291;125820;127879;128212.71787282008;152095.43713688606;178064;1306.3794697518792;1320.7688654485426;1365.549368708324;1370.1984339161502;1371.4102935737728;19265.22939082421;20571.280005199747;20700.792992069888;20734.585308294052;20848;53364.73317633542;66024.66987665031;66532.00201996877;66681.33318666751;67236.66381668061;69548;83001.58499207466;94440.52779736332;95303.52348238214;97764;10085.848712269175;11003;11597.362145082101;11924.344161071225;12005;10274.691759247235;12372.948299394653;13100.148490348098;13292.534761283308;13365.866341336463;9473.715788526355;11156.330620162817;11168;11190.94404527972;11551.942240288745;8323.167683231628;8325;9712.5143742814;9924.851127232952;9990.700278991642;11024.559017639205;11555.537778488766;11713.355765432983;11807.881921181022;11860.169788115094;8894.955525222333;11583;11641;11644.35956022393;11948;11118.888811111785;11277.887221127683;11527.769444610893;11555.884441155817;11611.6516504505 00:35:04 INFO - PROCESS | 1794 | |3;mootools.html;1520.414640363461;1581.9321957678571;1592.6098105964038;1597.233328002558;1601.4875239923226;11014;11185.272957257772;11584.362860042695;11698.94709476147;11699.707507312321;11124.443777811097;11287.943560282187;11288.774224515526;11290.491927863246;11295.265807722491;2424.9272521824328;2471.122751423246;2472.874841946919;2472.950540989179;2476.158106243877;2336.894839732208;2353.186172428159;2368.8933997970134;2373.2880135959144;2377.1442280778906;2030.7055476955832;2376.3821406434363;2433.793127584149;2445.8654773987423;2456.4718585504115;2359.9646005309764;2416.32342943976;2423.333583264599;2424.709034915804;2433.197044975154;2395.904163833462;2416.5287768885073;2417.468157005456;2419.794317483016;2421.4551725861716;11043.72390690231;11235.157363197794;11241.775164496747;11286.209965302432;11311.943440282746;5836.095405212156;5989.610675306091;6024.096385542204;6035.275766907956;6051.031834906394;3499.7550171488006;3610.981945090284;3611.5846677632144;3653.561572611277;3655.232401195752;1197.8143387774821;1206.6741979665528;1233.1306428967646;1255.4727014653868;1257.905657075723;12245.755084898252;13314.001449891297;13515.918726501855;15240.923795381063;15298.617534561614;4734.573888349996;4843.6367272454;4860.878478038077;4869.902601947942;4879.731614761151;11904.047676186057;12032.157748957665;12058.758824823455;12061.638150855488;12095.092868034933;5873.236479257669;5878;5903.793367232126;5909.970450147722;6000.459958603747;5224.764885580082;5387.568994480433;5422.75597598109;5422.810201642924;5464.28964234647;3814;3984.203159368138;3987.5613682494904;4016.397540368968;4040.797960102042;1020.321486211661;1021.9693409197736;1032.2670903658336;1039.3348257115451;1040.635777477892;942.3497786527274;951.8001219743793;973.6154219083422;974.1622204903778;1017.2421545948316;6316.431521163117;6649.501287403464;6700.430463410665;6730.899036514456;6917.342852429011;936.2603543200931;952.1716106986827;989.7183147823586;1018.9337693049928;1030.5311083457009;4038.1923615276473;4278.957210427856;4286.292761694285;4461.821527138943;4484.820607175741;13336.266505342299;14158.787618185735;14178.14931104137;14207;14226.786598201035;13363.198208107544;13589.048766586442;13798.448062077405;13845.861541384456;13857.722845543234;8594.398392112493;8841.248493878093;9844.212463002943;10090.646827361006;10202.387856728472;6532.41208291237;6586.242582103119;6600.306967768507;6621.602703837785;6719.563228390139;12264.938675306566;12418.875811242133;12482.188657737219;12604.810927835906;12652;11289.435528223721;12219.57231496929;12514.499420023098;12594;12767.297798621164;7971.202879711982;8088;8205.79485512867;8211.630476628447;8213.137620549756;10340.793184136426;10353.689389318333;10363.32638378503;10426.68719938403;10465.738356541147;8580.65677372898;8735.956320218358;8890.511021893599;8918.063603321818;9000.23498002651;8561.272291855013;8615.526146062026;8707.782305442415;8722.389432739647;8834.690785822466;7387.704491820482;7634.236576342321;7798.064232292161;7821.726239581587;7905.960470197612;4830.975845120752;5760.0783874579865;5832.965090587695;5969.820905372846;6072.514198864169;5850.2102216200265;5907.49786268172;5991.430814072656;6080.422359875804;6096 00:35:04 INFO - PROCESS | 1794 | |4;prototype.html;314.78909130882306;355.3798621405647;373.0840785870687;377.40935436042577;391.6279534442279;4728.787204575794;4836.951630483699;4933.7286449245275;5157.277981082647;5243.816466423676;5092;5113.846584602458;5122.769475373608;5153;5174.327337446136;3658.926821463569;3905.882823515285;4060.309747342959;4062.420363069443;4298.860801887495;3501.772384794993;3644.489771432002;3794.886153415388;3851.4222866569958;4299.53815702661;3699.870504532342;4189.874303770892;4321.903876619836;4336.677313419405;4346.78266086696;3357.4891298915586;4069.593040695907;4228.6617070634275;4305.74165550068;4317.568243175689;3374.7975121492545;3413.6927676508985;3638.399664055428;3787.867424640124;3957;5026.220935754964;5072.949270507284;5081.856582268997;5090.516400941905;5095.79616815327;4691.929621055687;4945.727984960826;4976.62675299354;5032.169692000815;5032.396112466491;4941.975290123562;4970.627202959757;5470.79642478654;5538.418466061086;5575.8606034849045;3987.501562304712;4155.10665206982;4448.4439445069365;4473.977630111829;4488.685791994561;5047.570956468705;5198.064348417283;5461.754221060062;5550.528205102531;5900.586958912878;3880.3403421418425;4016.9397459038137;4074.959250407458;4143.881152088919;4481.35020422037;4244;4457.621102206284;4600.746958917225;4601.608863246594;4610.9510086455275;4546.65900057497;4792.9281060784115;4957.479464656231;4985.332846926876;5031.748412579356;4505.324201369821;4887.242477415935;5219.9038201977255;5345.518903298722;5451;1369.7877378520081;1388.6181300142541;1434.7202295552268;1528.465037236958;1535.8924875258624;1107.3189988157387;1109.778044391125;1111.90548803351;1180.8110702287593;1190.7142285851507;4405.449318835145;5034.295198672189;5063.797448102108;5088.872778180576;5099.413567439792;5059.822906198266;5143.328418264079;5669.31968163823;5676.602637815313;5733.483986441241;4548.4541854977615;4992.450830408652;5046.243063540498;5100.744962751847;5117.590592752571;2837.4183292425005;2952.995981366346;2956.8669409876607;2974.7917645765024;2995.8951436699613;5200.777817212952;5246;5259.422173348057;5618.942951343742;5934.228550288522;4783.6651434399255;5138.946515964219;5625.774969001194;5743.62666426681;5874.882502350015;5121.846344609742;5577.6932268724795;5788.450097240755;5806.2451881255165;6033.698315084141;5543.918935807479;5586.357568879631;5792.652440853562;5809.651420914759;5819.70901454934;4909.582685471775;5428.918566221589;5584.972075139598;5644.491995720405;5685.573581981451;5825.660098177358;5906.064405272674;6001.909971350346;6024.1806554756895;6027.397260273938;3963.781991990468;4006.238814625212;4193.182329445729;4364.996050908246;4539.752822352956;4894.388201474816;5044.571211447068;5445.700486473282;5582.413846546055;5768.596198266081;5059.494050594911;5149.304843846033;5293.855988324734;5487.890242195213;5567.331920169606;4908.361912951294;4920.597629675525;5068.29043933857;5195.922061169161;5651.660900345993;4648.511906249931;5206.271122042841;5247.897941432189;5416.918746218731;5480.780283984993;4871.975640121776;5202.25724382321;5575.386707462257;5578.219049333015;5610.551155907436;4554.772261386984;5016.8610896010205;5201.505856943584;5424.755885985219;5503.807366742145 00:35:04 INFO - PROCESS | 1794 | |5;yui.html;704.2323866984985;748.8090536913087;764.9847003059939;766.1801871996965;788.1251810490359;15218.467353642625;16015.759763603555;16459.012459252437;16996.575085622866;17075.658486830256;13781.242031688253;15446.304916278767;15866.682666346665;15917.761233581505;15927.283272252747;944.3578366710634;967.6226271754005;993.3791380387258;997.436448406648;998.8301988661945;874.9850173798395;934.3552948465536;991.2862738828069;995.3778888194878;996.093554865073;1029.1560920045565;1035.4977835749655;1039.142707266508;1040.6513817870984;1040.9167266618651;1035.435687550287;1037.0873631204568;1038.7195457226544;1038.7506998320387;1039.2413538117216;766.513264077315;769.4306213402098;769.5767327969594;769.5959621198859;770.0528350129316;16467;16908.154592270337;16940.407085752064;17439.912800436043;17684.46946591604;14118.082324648865;14427.78358324626;14510.782338264935;14577.052491588012;14621.0496317739;9863.80272394548;11482.942585287105;11756.966214296828;11760.17678762487;12003.819942700866;3763.9059023524355;3879.573246942834;3906.277338692351;3906.7655940643654;3907.27715372657;62563;71664.92502612412;100814.49592752062;101086.4945675267;104491.47754261256;11086.390248536247;11190.32858028521;11265.380404077854;11316.98147166759;12090.879091209152;22768.316950491513;23005;23005.539889202122;23026.309210723706;23042.884785575963;7927.484713493604;7995;8045.919540804517;8148.981377327834;8282.668693252323;4397.538258482813;4398.824047038147;4427.800748966304;4476.6418686505;4506.59440650343;3396.4735466002808;3435.450327947566;3469.9653003469643;3474.3225071111397;3477.2176260341726;599.1282683695213;601.9668918209453;605.8515663662431;606.848287928018;609.0985341694317;686.3308274432388;691.7578847403369;701.2371257246433;721.195866608738;724.8803947348629;9392.685024096496;9576.76058098553;10478;10656.20078494116;10800.78398432027;631.3307893632764;640.6476437959104;678.3657280442703;697.330562659842;697.4838619421591;4975.514785493648;5045.823396181116;5052.444231134572;5054.848354549369;5061.822836200715;89839.10160898438;107345;107584;108471;109258;57826.7108664454;62290.68854655698;83540;86694.56652716696;91344.54327728452;53076.73461632667;74295;77840.83238751424;78190.60904695553;78825.60587197142;10982.945085274523;11143.762202653652;11254.88745112571;11334.489947952197;11380.260283081574;42708.57291427046;47938.56184314476;49666.50333496619;49819.25632602986;50012;62439.063414047916;63082;63902.36097638964;64828.0275795854;65254.67372663107;11017.944910275397;11284.661460156209;11360.03439707634;12136.39318034077;12879.291638959947;14611.92694036523;14655.047421917541;14886.404543818126;15170.393184272505;15399.923000384926;11973.10201734856;12305.815412768994;13723.313834308045;14315.14109153454;15018.94867359318;11438.42807859587;12401.813972790596;13750.312484375941;14032.579022629337;14278.000539962102;13169.868301316865;13238.669033274246;13447.462101516221;13675.179489230679;13707.383167757675;9592.142384452141;9725.247277450351;9740.853887191555;10106.14097801695;10217.59129634836;9432.52837358143;9470;11266.66200014001;11410.258333208314;11495.942520287344 00:35:04 INFO - PROCESS | 1794 | __end_tp_report 00:35:04 INFO - PROCESS | 1794 | __start_cc_report 00:35:04 INFO - PROCESS | 1794 | _x_x_mozilla_cycle_collect,378 00:35:04 INFO - PROCESS | 1794 | __end_cc_report 00:35:04 INFO - PROCESS | 1794 | __startTimestamp1495524904008__endTimestamp 00:35:04 INFO - PROCESS | 1794 | 00:35:04 INFO - PROCESS | 1794 | ------- Summary: start ------- 00:35:04 INFO - PROCESS | 1794 | Number of tests: 6 00:35:04 INFO - PROCESS | 1794 | 00:35:04 INFO - PROCESS | 1794 | [#0] dojo.html Cycles:165 Average:5977.30 Median:6237.56 stddev:667.01 (10.7%) stddev-sans-first:639.25 00:35:04 INFO - PROCESS | 1794 | Values: 3463.9 3482.4 3504.2 3504.4 3517.5 5983.6 6013.0 6065.6 6112.9 6124.3 5001.8 6082.9 6112.5 6142.0 6191.4 5423.7 5501.9 5508.2 5523.1 5651.8 5562.7 5565.1 5582.5 5595.0 5596.2 5368.1 5386.4 5575.9 5579.1 5587.8 5470.1 5532.8 5534.9 5552.4 5555.4 5406.2 5513.4 5525.2 5536.8 5543.2 5093.3 5697.9 6120.9 6217.2 6241.0 6205.6 6289.1 6304.3 6401.0 6403.1 6625.7 6636.6 6637.6 6653.8 6678.1 6173.2 6205.7 6237.6 6278.2 6343.7 6338.4 6440.4 6543.5 6685.8 6755.8 6235.8 6268.2 6324.7 6329.1 6342.1 6514.4 6519.0 6549.5 6589.8 6593.9 6180.4 6381.5 6466.2 6470.8 6490.7 6261.6 6308.8 6319.7 6360.9 6399.0 5207.3 5218.9 5220.6 5223.3 5245.7 4593.2 4623.3 4636.0 4643.4 4680.0 5900.5 5947.2 5950.9 5962.6 6050.8 6447.0 6570.7 6572.8 6573.8 6585.2 5130.6 5540.8 5756.7 5894.1 5943.8 4911.3 5661.4 5764.3 5766.7 5774.6 5285.9 6401.0 6435.5 6642.2 6792.4 5870.8 6540.7 6546.4 6570.7 6593.2 5623.6 6206.7 6283.6 6307.9 6772.9 6274.7 6300.4 6332.6 6435.2 6461.3 6494.6 6546.2 6553.4 6564.6 6585.9 5396.3 6188.3 6339.2 6368.8 6381.5 6244.4 6277.5 6290.1 6370.3 6415.7 6147.7 6255.8 6337.5 6339.6 6366.7 6389.5 6401.6 6428.3 6428.9 6429.6 6137.6 6311.9 6318.8 6328.3 6346.8 00:35:04 INFO - PROCESS | 1794 | 00:35:04 INFO - PROCESS | 1794 | [#1] ext.html Cycles:180 Average:53462.71 Median:30036.47 stddev:108688.62 (361.9%) stddev-sans-first:108921.50 00:35:04 INFO - PROCESS | 1794 | Values: 764.9 810.2 833.4 973.8 974.0 33948.5 38887.2 39734.4 41364.0 41906.6 40384.6 40517.4 40533.6 40579.0 40719.6 6917.7 8218.9 10926.7 10933.6 11266.6 9736.6 11108.5 11126.7 11146.4 11167.5 11153.1 11158.2 11182.7 11196.0 11208.4 11121.3 11121.6 11175.3 11189.3 11288.5 10931.9 10981.3 11001.2 11017.3 11020.5 35679.0 35815.6 35872.5 35997.0 36482.6 3817.6 3828.8 3843.6 3865.0 3905.9 25777.4 26636.6 27253.6 27292.9 27326.0 2568.4 3153.7 3173.5 3190.7 3304.3 258900.0 285046.0 296203.0 296279.0 296490.0 38552.8 44419.9 44537.0 44641.1 44681.0 34933.7 37660.0 45989.0 46128.8 46133.8 14234.4 14380.6 14396.5 14423.9 14435.1 12482.9 12502.6 12503.9 12520.6 12529.2 11158.4 12248.5 12293.4 12309.2 12402.1 4770.6 4803.0 5711.5 5747.5 5765.5 1128.3 1140.0 1143.9 1159.1 1216.8 26964.9 26999.3 27219.3 27329.6 27415.9 1234.3 1251.2 1252.9 1306.2 1311.9 11930.6 14052.6 14205.8 14345.6 14377.8 480692.0 482001.0 483058.0 483508.0 484345.0 44331.8 44535.1 44638.8 44643.0 44681.3 1462.8 1476.5 1510.0 1513.8 1517.1 25825.5 26736.7 27235.6 27252.5 27330.0 47769.3 47916.5 48255.5 48381.3 48694.5 430978.0 432064.0 432092.0 433103.0 433617.0 31894.8 32029.4 32108.5 32173.4 32175.8 43733.9 44167.6 45316.3 45555.5 45668.3 30006.8 30066.1 30389.4 30821.7 30885.8 21205.0 22799.7 29942.7 30747.4 30877.2 36022.5 36197.6 36561.6 36709.6 36830.4 28148.7 29136.3 29665.9 29944.1 31016.5 23604.6 23617.6 23863.8 23989.0 24035.6 00:35:04 INFO - PROCESS | 1794 | 00:35:04 INFO - PROCESS | 1794 | [#2] jquery.html Cycles:180 Average:42683.88 Median:23358.86 stddev:124221.62 (531.8%) stddev-sans-first:124529.40 00:35:04 INFO - PROCESS | 1794 | Values: 340.6 400.5 408.3 410.2 456.6 20506.9 21916.6 22357.7 23146.4 23173.3 18187.5 22653.5 22704.0 23008.8 23754.5 6815.0 6961.1 7010.0 7087.5 7088.0 5806.0 5827.6 6584.3 6927.7 6932.2 6704.4 6807.5 6860.3 6922.1 6949.0 6926.9 6942.6 7000.3 7009.5 7030.7 6742.1 6773.4 6815.7 6822.8 6830.8 20354.0 23593.6 25512.9 27106.0 27630.6 2988.8 2990.9 3041.3 3063.8 3077.5 16789.7 16837.7 17033.0 17095.0 17169.2 2004.9 2018.1 2020.2 2027.7 2062.0 133588.0 134396.0 137369.0 138580.3 138742.0 19065.9 23014.2 23544.4 24435.5 24465.0 37370.1 37425.6 37672.4 38114.2 38401.0 11211.8 11522.1 11531.3 11679.0 11859.5 9360.0 9374.8 9392.4 9537.5 9555.4 9109.9 9455.3 10928.3 11161.6 11264.3 4053.0 4446.8 4498.1 4522.4 4524.2 791.6 802.8 822.9 915.5 960.6 18107.9 22424.3 22742.7 22785.5 22819.7 965.8 981.4 1003.3 1008.9 1010.1 11346.9 11886.9 12076.9 12077.0 12132.6 716791.0 729250.0 747943.0 761543.2 785291.0 125820.0 127879.0 128212.7 152095.4 178064.0 1306.4 1320.8 1365.5 1370.2 1371.4 19265.2 20571.3 20700.8 20734.6 20848.0 53364.7 66024.7 66532.0 66681.3 67236.7 69548.0 83001.6 94440.5 95303.5 97764.0 10085.8 11003.0 11597.4 11924.3 12005.0 10274.7 12372.9 13100.1 13292.5 13365.9 9473.7 11156.3 11168.0 11190.9 11551.9 8323.2 8325.0 9712.5 9924.9 9990.7 11024.6 11555.5 11713.4 11807.9 11860.2 8895.0 11583.0 11641.0 11644.4 11948.0 11118.9 11277.9 11527.8 11555.9 11611.7 00:35:04 INFO - PROCESS | 1794 | 00:35:04 INFO - PROCESS | 1794 | [#3] mootools.html Cycles:180 Average:6732.45 Median:2463.80 stddev:4174.80 (169.4%) stddev-sans-first:4168.15 00:35:04 INFO - PROCESS | 1794 | Values: 1520.4 1581.9 1592.6 1597.2 1601.5 11014.0 11185.3 11584.4 11698.9 11699.7 11124.4 11287.9 11288.8 11290.5 11295.3 2424.9 2471.1 2472.9 2473.0 2476.2 2336.9 2353.2 2368.9 2373.3 2377.1 2030.7 2376.4 2433.8 2445.9 2456.5 2360.0 2416.3 2423.3 2424.7 2433.2 2395.9 2416.5 2417.5 2419.8 2421.5 11043.7 11235.2 11241.8 11286.2 11311.9 5836.1 5989.6 6024.1 6035.3 6051.0 3499.8 3611.0 3611.6 3653.6 3655.2 1197.8 1206.7 1233.1 1255.5 1257.9 12245.8 13314.0 13515.9 15240.9 15298.6 4734.6 4843.6 4860.9 4869.9 4879.7 11904.0 12032.2 12058.8 12061.6 12095.1 5873.2 5878.0 5903.8 5910.0 6000.5 5224.8 5387.6 5422.8 5422.8 5464.3 3814.0 3984.2 3987.6 4016.4 4040.8 1020.3 1022.0 1032.3 1039.3 1040.6 942.3 951.8 973.6 974.2 1017.2 6316.4 6649.5 6700.4 6730.9 6917.3 936.3 952.2 989.7 1018.9 1030.5 4038.2 4279.0 4286.3 4461.8 4484.8 13336.3 14158.8 14178.1 14207.0 14226.8 13363.2 13589.0 13798.4 13845.9 13857.7 8594.4 8841.2 9844.2 10090.6 10202.4 6532.4 6586.2 6600.3 6621.6 6719.6 12264.9 12418.9 12482.2 12604.8 12652.0 11289.4 12219.6 12514.5 12594.0 12767.3 7971.2 8088.0 8205.8 8211.6 8213.1 10340.8 10353.7 10363.3 10426.7 10465.7 8580.7 8736.0 8890.5 8918.1 9000.2 8561.3 8615.5 8707.8 8722.4 8834.7 7387.7 7634.2 7798.1 7821.7 7906.0 4831.0 5760.1 5833.0 5969.8 6072.5 5850.2 5907.5 5991.4 6080.4 6096.0 00:35:04 INFO - PROCESS | 1794 | 00:35:04 INFO - PROCESS | 1794 | [#4] prototype.html Cycles:180 Average:4557.40 Median:5021.54 stddev:1290.22 (25.7%) stddev-sans-first:1253.93 00:35:04 INFO - PROCESS | 1794 | Values: 314.8 355.4 373.1 377.4 391.6 4728.8 4837.0 4933.7 5157.3 5243.8 5092.0 5113.8 5122.8 5153.0 5174.3 3658.9 3905.9 4060.3 4062.4 4298.9 3501.8 3644.5 3794.9 3851.4 4299.5 3699.9 4189.9 4321.9 4336.7 4346.8 3357.5 4069.6 4228.7 4305.7 4317.6 3374.8 3413.7 3638.4 3787.9 3957.0 5026.2 5072.9 5081.9 5090.5 5095.8 4691.9 4945.7 4976.6 5032.2 5032.4 4942.0 4970.6 5470.8 5538.4 5575.9 3987.5 4155.1 4448.4 4474.0 4488.7 5047.6 5198.1 5461.8 5550.5 5900.6 3880.3 4016.9 4075.0 4143.9 4481.4 4244.0 4457.6 4600.7 4601.6 4611.0 4546.7 4792.9 4957.5 4985.3 5031.7 4505.3 4887.2 5219.9 5345.5 5451.0 1369.8 1388.6 1434.7 1528.5 1535.9 1107.3 1109.8 1111.9 1180.8 1190.7 4405.4 5034.3 5063.8 5088.9 5099.4 5059.8 5143.3 5669.3 5676.6 5733.5 4548.5 4992.5 5046.2 5100.7 5117.6 2837.4 2953.0 2956.9 2974.8 2995.9 5200.8 5246.0 5259.4 5618.9 5934.2 4783.7 5138.9 5625.8 5743.6 5874.9 5121.8 5577.7 5788.5 5806.2 6033.7 5543.9 5586.4 5792.7 5809.7 5819.7 4909.6 5428.9 5585.0 5644.5 5685.6 5825.7 5906.1 6001.9 6024.2 6027.4 3963.8 4006.2 4193.2 4365.0 4539.8 4894.4 5044.6 5445.7 5582.4 5768.6 5059.5 5149.3 5293.9 5487.9 5567.3 4908.4 4920.6 5068.3 5195.9 5651.7 4648.5 5206.3 5247.9 5416.9 5480.8 4872.0 5202.3 5575.4 5578.2 5610.6 4554.8 5016.9 5201.5 5424.8 5503.8 00:35:04 INFO - PROCESS | 1794 | 00:35:04 INFO - PROCESS | 1794 | [#5] yui.html Cycles:180 Average:19539.39 Median:3452.71 stddev:27122.23 (785.5%) stddev-sans-first:27161.44 00:35:04 INFO - PROCESS | 1794 | Values: 704.2 748.8 765.0 766.2 788.1 15218.5 16015.8 16459.0 16996.6 17075.7 13781.2 15446.3 15866.7 15917.8 15927.3 944.4 967.6 993.4 997.4 998.8 875.0 934.4 991.3 995.4 996.1 1029.2 1035.5 1039.1 1040.7 1040.9 1035.4 1037.1 1038.7 1038.8 1039.2 766.5 769.4 769.6 769.6 770.1 16467.0 16908.2 16940.4 17439.9 17684.5 14118.1 14427.8 14510.8 14577.1 14621.0 9863.8 11482.9 11757.0 11760.2 12003.8 3763.9 3879.6 3906.3 3906.8 3907.3 62563.0 71664.9 100814.5 101086.5 104491.5 11086.4 11190.3 11265.4 11317.0 12090.9 22768.3 23005.0 23005.5 23026.3 23042.9 7927.5 7995.0 8045.9 8149.0 8282.7 4397.5 4398.8 4427.8 4476.6 4506.6 3396.5 3435.5 3470.0 3474.3 3477.2 599.1 602.0 605.9 606.8 609.1 686.3 691.8 701.2 721.2 724.9 9392.7 9576.8 10478.0 10656.2 10800.8 631.3 640.6 678.4 697.3 697.5 4975.5 5045.8 5052.4 5054.8 5061.8 89839.1 107345.0 107584.0 108471.0 109258.0 57826.7 62290.7 83540.0 86694.6 91344.5 53076.7 74295.0 77840.8 78190.6 78825.6 10982.9 11143.8 11254.9 11334.5 11380.3 42708.6 47938.6 49666.5 49819.3 50012.0 62439.1 63082.0 63902.4 64828.0 65254.7 11017.9 11284.7 11360.0 12136.4 12879.3 14611.9 14655.0 14886.4 15170.4 15399.9 11973.1 12305.8 13723.3 14315.1 15018.9 11438.4 12401.8 13750.3 14032.6 14278.0 13169.9 13238.7 13447.5 13675.2 13707.4 9592.1 9725.2 9740.9 10106.1 10217.6 9432.5 9470.0 11266.7 11410.3 11495.9 00:35:04 INFO - PROCESS | 1794 | -------- Summary: end -------- 00:35:04 INFO - PROCESS | 1794 | 00:35:04 INFO - TEST-INFO | 1794: exit 0 00:35:04 INFO - TEST-OK | dromaeo_css | took 1116126ms 00:35:04 INFO - TEST-START | kraken 00:35:04 INFO - operating with platform_type : mac_ 00:35:04 INFO - Initialising browser for kraken test... 00:35:04 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmpgbuRRG/profile http://localhost:49249/getInfo.html) 00:35:07 INFO - PROCESS | 1913 | 2017-05-23 00:35:07.254 plugin-container[1915:11705] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 00:35:07 INFO - PROCESS | 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:35:07 INFO - PROCESS | 1913 | 2017-05-23 00:35:07.254 plugin-container[1915:11705] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1b, name = 'com.apple.CFPasteboardClient' 00:35:07 INFO - PROCESS | 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:35:07 INFO - PROCESS | 1913 | 2017-05-23 00:35:07.254 plugin-container[1915:11705] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:35:07 INFO - PROCESS | 1913 | __metrics Screen width/height:1600/1200 00:35:07 INFO - PROCESS | 1913 | colorDepth:24 00:35:07 INFO - PROCESS | 1913 | Browser inner width/height: 1024/689 00:35:07 INFO - PROCESS | 1913 | __metrics 00:35:07 ERROR - PROCESS | 1913 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 00:35:07 INFO - TEST-INFO | 1913: exit 0 00:35:07 INFO - Browser initialized. 00:35:07 INFO - Running cycle 1/1 for kraken test... 00:35:07 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmpgbuRRG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:35:10 INFO - PROCESS | 1916 | 2017-05-23 00:35:10.448 plugin-container[1917:11833] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 00:35:10 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:35:10 INFO - PROCESS | 1916 | 2017-05-23 00:35:10.448 plugin-container[1917:11833] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 00:35:10 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:35:10 INFO - PROCESS | 1916 | 2017-05-23 00:35:10.448 plugin-container[1917:11833] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:35:20 INFO - PROCESS | 1916 | RSS: Main: 202756096 00:35:20 INFO - PROCESS | 1916 | 00:35:23 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 00:35:24 INFO - PROCESS | 1916 | RSS: Main: 216256512 00:35:24 INFO - PROCESS | 1916 | 00:35:26 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 00:35:27 INFO - PROCESS | 1916 | RSS: Main: 224268288 00:35:27 INFO - PROCESS | 1916 | 00:35:30 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 00:35:31 INFO - PROCESS | 1916 | RSS: Main: 227921920 00:35:31 INFO - PROCESS | 1916 | 00:35:33 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 00:35:33 INFO - PROCESS | 1916 | RSS: Main: 228376576 00:35:33 INFO - PROCESS | 1916 | 00:35:35 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:35:36 INFO - PROCESS | 1916 | RSS: Main: 228261888 00:35:36 INFO - PROCESS | 1916 | 00:35:39 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 00:35:40 INFO - PROCESS | 1916 | RSS: Main: 233648128 00:35:40 INFO - PROCESS | 1916 | 00:35:43 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 00:35:43 INFO - PROCESS | 1916 | RSS: Main: 233234432 00:35:43 INFO - PROCESS | 1916 | 00:35:46 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 00:35:47 INFO - PROCESS | 1916 | RSS: Main: 233701376 00:35:47 INFO - PROCESS | 1916 | 00:35:49 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:35:49 INFO - PROCESS | 1916 | RSS: Main: 232534016 00:35:49 INFO - PROCESS | 1916 | 00:35:51 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:35:52 INFO - PROCESS | 1916 | RSS: Main: 232488960 00:35:52 INFO - PROCESS | 1916 | 00:35:54 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:35:55 INFO - PROCESS | 1916 | RSS: Main: 232452096 00:35:55 INFO - PROCESS | 1916 | 00:35:57 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:35:58 INFO - PROCESS | 1916 | RSS: Main: 232923136 00:35:58 INFO - PROCESS | 1916 | 00:36:01 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:36:02 INFO - PROCESS | 1916 | RSS: Main: 232873984 00:36:02 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 00:36:04 INFO - PROCESS | 1916 | RSS: Main: 234610688 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | __start_tp_report 00:36:04 INFO - PROCESS | 1916 | _x_x_mozilla_page_load 00:36:04 INFO - PROCESS | 1916 | _x_x_mozilla_page_load_details 00:36:04 INFO - PROCESS | 1916 | |i|pagename|runs| 00:36:04 INFO - PROCESS | 1916 | |0;ai-astar;113;142;120;120;120;121;118;119;119;118 00:36:04 INFO - PROCESS | 1916 | |1;audio-beat-detection;146;128;142;140;145;146;147;116;131;148 00:36:04 INFO - PROCESS | 1916 | |2;audio-dft;199;201;196;199;202;197;191;196;206;199 00:36:04 INFO - PROCESS | 1916 | |3;audio-fft;79;61;60;91;63;63;64;62;77;61 00:36:04 INFO - PROCESS | 1916 | |4;audio-oscillator;88;70;88;102;103;80;81;73;71;82 00:36:04 INFO - PROCESS | 1916 | |5;imaging-gaussian-blur;88;88;87;93;92;88;88;87;89;87 00:36:04 INFO - PROCESS | 1916 | |6;imaging-darkroom;96;99;96;106;95;95;96;97;95;116 00:36:04 INFO - PROCESS | 1916 | |7;imaging-desaturate;89;95;91;90;90;89;88;81;139;146 00:36:04 INFO - PROCESS | 1916 | |8;json-parse-financial;67;66;68;68;71;70;68;68;69;70 00:36:04 INFO - PROCESS | 1916 | |9;json-stringify-tinderbox;62;55;58;63;61;74;81;82;82;57 00:36:04 INFO - PROCESS | 1916 | |10;stanford-crypto-aes;89;98;89;82;86;86;86;83;92;93 00:36:04 INFO - PROCESS | 1916 | |11;stanford-crypto-ccm;141;138;136;137;139;137;147;156;195;149 00:36:04 INFO - PROCESS | 1916 | |12;stanford-crypto-pbkdf2;209;167;230;202;167;166;169;169;170;170 00:36:04 INFO - PROCESS | 1916 | |13;stanford-crypto-sha256-iterative;56;74;85;88;88;85;67;65;66;66 00:36:04 INFO - PROCESS | 1916 | __end_tp_report 00:36:04 INFO - PROCESS | 1916 | __start_cc_report 00:36:04 INFO - PROCESS | 1916 | _x_x_mozilla_cycle_collect,1019 00:36:04 INFO - PROCESS | 1916 | __end_cc_report 00:36:04 INFO - PROCESS | 1916 | __startTimestamp1495524964358__endTimestamp 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | ------- Summary: start ------- 00:36:04 INFO - PROCESS | 1916 | Number of tests: 14 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | [#0] ai-astar Cycles:10 Average:121.00 Median:120.00 stddev:7.70 (6.4%) stddev-sans-first:7.61 00:36:04 INFO - PROCESS | 1916 | Values: 113.0 142.0 120.0 120.0 120.0 121.0 118.0 119.0 119.0 118.0 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:145.50 stddev:10.56 (7.3%) stddev-sans-first:10.88 00:36:04 INFO - PROCESS | 1916 | Values: 146.0 128.0 142.0 140.0 145.0 146.0 147.0 116.0 131.0 148.0 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | [#2] audio-dft Cycles:10 Average:198.60 Median:199.00 stddev:4.03 (2.0%) stddev-sans-first:4.28 00:36:04 INFO - PROCESS | 1916 | Values: 199.0 201.0 196.0 199.0 202.0 197.0 191.0 196.0 206.0 199.0 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | [#3] audio-fft Cycles:10 Average:68.10 Median:63.50 stddev:10.51 (16.6%) stddev-sans-first:10.39 00:36:04 INFO - PROCESS | 1916 | Values: 79.0 61.0 60.0 91.0 63.0 63.0 64.0 62.0 77.0 61.0 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | [#4] audio-oscillator Cycles:10 Average:83.80 Median:80.50 stddev:11.70 (14.5%) stddev-sans-first:12.31 00:36:04 INFO - PROCESS | 1916 | Values: 88.0 70.0 88.0 102.0 103.0 80.0 81.0 73.0 71.0 82.0 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:88.00 stddev:2.11 (2.4%) stddev-sans-first:2.22 00:36:04 INFO - PROCESS | 1916 | Values: 88.0 88.0 87.0 93.0 92.0 88.0 88.0 87.0 89.0 87.0 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | [#6] imaging-darkroom Cycles:10 Average:99.10 Median:96.00 stddev:6.81 (7.1%) stddev-sans-first:7.13 00:36:04 INFO - PROCESS | 1916 | Values: 96.0 99.0 96.0 106.0 95.0 95.0 96.0 97.0 95.0 116.0 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | [#7] imaging-desaturate Cycles:10 Average:99.80 Median:89.50 stddev:22.83 (25.5%) stddev-sans-first:23.87 00:36:04 INFO - PROCESS | 1916 | Values: 89.0 95.0 91.0 90.0 90.0 89.0 88.0 81.0 139.0 146.0 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | [#8] json-parse-financial Cycles:10 Average:68.50 Median:68.50 stddev:1.51 (2.2%) stddev-sans-first:1.50 00:36:04 INFO - PROCESS | 1916 | Values: 67.0 66.0 68.0 68.0 71.0 70.0 68.0 68.0 69.0 70.0 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | [#9] json-stringify-tinderbox Cycles:10 Average:67.50 Median:68.50 stddev:11.03 (16.1%) stddev-sans-first:11.52 00:36:04 INFO - PROCESS | 1916 | Values: 62.0 55.0 58.0 63.0 61.0 74.0 81.0 82.0 82.0 57.0 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:89.00 stddev:4.88 (5.5%) stddev-sans-first:5.17 00:36:04 INFO - PROCESS | 1916 | Values: 89.0 98.0 89.0 82.0 86.0 86.0 86.0 83.0 92.0 93.0 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | [#11] stanford-crypto-ccm Cycles:10 Average:147.50 Median:144.00 stddev:17.91 (12.4%) stddev-sans-first:18.85 00:36:04 INFO - PROCESS | 1916 | Values: 141.0 138.0 136.0 137.0 139.0 137.0 147.0 156.0 195.0 149.0 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.90 Median:170.00 stddev:23.01 (13.5%) stddev-sans-first:22.22 00:36:04 INFO - PROCESS | 1916 | Values: 209.0 167.0 230.0 202.0 167.0 166.0 169.0 169.0 170.0 170.0 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - PROCESS | 1916 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:79.50 stddev:11.62 (14.6%) stddev-sans-first:10.34 00:36:04 INFO - PROCESS | 1916 | Values: 56.0 74.0 85.0 88.0 88.0 85.0 67.0 65.0 66.0 66.0 00:36:04 INFO - PROCESS | 1916 | -------- Summary: end -------- 00:36:04 INFO - PROCESS | 1916 | 00:36:04 INFO - TEST-INFO | 1916: exit 0 00:36:05 INFO - TEST-OK | kraken | took 60534ms 00:36:05 INFO - SUITE-END | took 1176s 00:36:05 INFO - Completed test suite (00:19:36) 00:36:05 INFO - javascript benchmark 00:36:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7724.102709409607, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3463.948040779389, 3482.4079906415905, 3504.2115524007086, 3504.386732321843, 3517.4547945068507, 5983.640981541111, 6013.037913933771, 6065.636061836281, 6112.938870611292, 6124.265088189412, 5001.8249361272365, 6082.878342433149, 6112.541559383042, 6142.017277235644, 6191.442770150686, 5423.728813559336, 5501.889962200774, 5508.173773933902, 5523.143912693539, 5651.7739290428335, 5562.694051827169, 5565.1374037024125, 5582.49757521823, 5595.048841696901, 5596.244506991545, 5368.087425137716, 5386.380566234874, 5575.916361254584, 5579.13523403871, 5587.804426845061, 5470.070088085035, 5532.806351777688, 5534.889302213933, 5552.3892371839065, 5555.416681248492, 5406.162044883049, 5513.365962914276, 5525.171224316385, 5536.806211782568, 5543.168524721284, 5093.261477085802, 5697.914531282034, 6120.938790612081, 6217.191765070557, 6240.968795156041, 6205.596636218631, 6289.119523266746, 6304.27500837405, 6401.0, 6403.135576697133, 6625.701843417058, 6636.568623039487, 6637.634930078843, 6653.833654158636, 6678.09845670838, 6173.228346456693, 6205.658688772116, 6237.563370564017, 6278.246610406781, 6343.746250150035, 6338.366163383625, 6440.420362167428, 6543.509236807164, 6685.79942601713, 6755.763548275787, 6235.781747638811, 6268.2478102627065, 6324.683765811691, 6329.145565348711, 6342.080398342307, 6514.413702766774, 6518.967405162944, 6549.4760419167305, 6589.835254118685, 6593.86812263752, 6180.351063138306, 6381.54310590426, 6466.224053113657, 6470.805875823733, 6490.740370385131, 6261.624302541862, 6308.842278943063, 6319.652419116901, 6360.9045864312075, 6399.0, 5207.270982062439, 5218.869528261824, 5220.556252718486, 5223.268742376071, 5245.7114858683135, 4593.150267200579, 4623.260278355447, 4635.956909695292, 4643.419572553431, 4680.040591678698, 5900.468957793819, 5947.197128387612, 5950.9107363389585, 5962.612430192024, 6050.848728781815, 6447.032657154552, 6570.671466426659, 6572.80281591553, 6573.769918052845, 6585.2426970898005, 5130.589552835764, 5540.7533305006855, 5756.712164391847, 5894.115882617642, 5943.762249509971, 4911.263310503453, 5661.405552416937, 5764.337101233328, 5766.654000759968, 5774.566907481955, 5285.916387140704, 6401.0, 6435.4529864962005, 6642.169728783902, 6792.422644075309, 5870.765169393177, 6540.738370465128, 6546.378094081055, 6570.704318305783, 6593.241777195492, 5623.634463759842, 6206.658633775186, 6283.622982621057, 6307.873842523216, 6772.864542709217, 6274.6548939808745, 6300.401461861115, 6332.588381755356, 6435.22777266731, 6461.321561236003, 6494.64279464634, 6546.24718157418, 6553.442957348679, 6564.606123632598, 6585.901211481927, 5396.325459317585, 6188.288346840171, 6339.175907132043, 6368.808935731936, 6381.521385895983, 6244.438000579969, 6277.497800176066, 6290.056491526308, 6370.299267080525, 6415.679216039272, 6147.692615369124, 6255.78104766331, 6337.4613157882095, 6339.587926784744, 6366.713497892513, 6389.48884089281, 6401.64790936503, 6428.325025872216, 6428.935710642833, 6429.58207716497, 6137.631742095488, 6311.936880631319, 6318.778842740482, 6328.335524769833, 6346.841328966812], "value": 5936.7893232221895, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.866148424026, 810.2140923304396, 833.3832963606933, 973.8393165127759, 973.9853902191464, 33948.49077263837, 38887.22225555491, 39734.403983940225, 41363.965900852454, 41906.58093419065, 40384.59615403845, 40517.39223911644, 40533.59466405335, 40579.0, 40719.59280407195, 6917.688704008319, 8218.917810821906, 10926.68879734433, 10933.56265749369, 11266.605668801594, 9736.610535578604, 11108.500117494732, 11126.721831954226, 11146.386948717818, 11167.497462614161, 11153.10775137987, 11158.218924675277, 11182.720431989224, 11196.048335891459, 11208.439578021107, 11121.27711698741, 11121.55513779448, 11175.329480231172, 11189.328640281608, 11288.492017859217, 10931.945340273247, 10981.341119532774, 11001.22991390603, 11017.338959662446, 11020.50407731646, 35679.0, 35815.64184358149, 35872.461913071325, 35997.0, 36482.63517364819, 3817.560980487224, 3828.846846126152, 3843.5964223756664, 3865.033741564609, 3905.921881562353, 25777.35556611081, 26636.600450993057, 27253.591196132074, 27292.863535682394, 27326.0, 2568.3964268396912, 3153.668864769189, 3173.4763763978917, 3190.680931906814, 3304.289577740797, 258900.0, 285046.0, 296203.0, 296279.0, 296490.0, 38552.80723596364, 44419.889502762686, 44537.0, 44641.10717785691, 44681.0, 34933.65066349355, 37660.0, 45989.0, 46128.769356153, 46133.84665383392, 14234.359453824603, 14380.56858294253, 14396.496122635657, 14423.85576144246, 14435.133891966516, 12482.937585312196, 12502.624921252194, 12503.937480312723, 12520.624381268395, 12529.248245105322, 11158.386288754034, 12248.510059597695, 12293.446794894251, 12309.199902006341, 12402.13185077054, 4770.594499467515, 4803.0, 5711.543076553866, 5747.511461525817, 5765.538756899438, 1128.2891778179694, 1140.0, 1143.857017872766, 1159.0785325666116, 1216.7870622641003, 26964.86517567439, 26999.325016874736, 27219.319517012234, 27329.590056149173, 27415.862920685267, 1234.3087870792385, 1251.2429979862234, 1252.9310887901254, 1306.1705816806207, 1311.9081664283408, 11930.642080737418, 14052.648683782783, 14205.78691319631, 14345.569632911029, 14377.78433323501, 480692.0, 482001.0, 483058.0, 483508.0, 484345.0, 44331.77834110809, 44535.10929781386, 44638.77680611576, 44643.0, 44681.329780053326, 1462.817147856518, 1476.4906107392933, 1510.0335785097545, 1513.788069670247, 1517.1276516003343, 25825.483490329712, 26736.732632673426, 27235.59146612842, 27252.454950900476, 27330.0, 47769.28346074742, 47916.520834791205, 48255.517444825105, 48381.27428088511, 48694.53916382514, 430978.0, 432064.0, 432092.0, 433103.0, 433617.0, 31894.840525797223, 32029.359412811147, 32108.518372224902, 32173.35653286968, 32175.839120804245, 43733.90665233395, 44167.55832441634, 45316.32025519554, 45555.54444455646, 45668.314975274734, 30006.849965750032, 30066.098017059525, 30389.392212156075, 30821.69178308278, 30885.845570772002, 21205.0, 22799.65800513027, 29942.700572993992, 30747.385052299276, 30877.228069298446, 36022.4596631056, 36197.63802361942, 36561.63438365582, 36709.63290367062, 36830.44754328633, 28148.71851281461, 29136.29981693062, 29665.851670741507, 29944.101676949726, 31016.534751978288, 23604.645930311402, 23617.64573531364, 23863.761362386154, 23989.0, 24035.639465407683], "value": 18510.946029485393, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.63551999360686, 400.53737932687744, 408.30791807885646, 410.16940695092455, 456.64381782209887, 20506.89746551267, 21916.57587237489, 22357.664635030465, 23146.42133946652, 23173.304800856, 18187.545311367223, 22653.54692906145, 22703.956643902045, 23008.76991230087, 23754.5249095018, 6815.0, 6961.060256865309, 7010.0, 7087.468439867005, 7088.007678924954, 5806.0, 5827.562932780037, 6584.341565843425, 6927.722891084376, 6932.202796678396, 6704.430123439513, 6807.523473356842, 6860.279670634562, 6922.134733158356, 6948.992396102562, 6926.896096558556, 6942.618156001393, 7000.26497217795, 7009.509334346598, 7030.683619237147, 6742.089817874573, 6773.3903948644365, 6815.693293801791, 6822.795316140524, 6830.7609233676585, 20354.0, 23593.646095308413, 25512.872435637888, 27106.0, 27630.585541216897, 2988.8206707597396, 2990.8654110565076, 3041.3309072004345, 3063.846807659608, 3077.476828939086, 16789.748153777702, 16837.747433788503, 17032.978021318762, 17094.974301541948, 17169.227384767717, 2004.859659823813, 2018.1221168791624, 2020.1919232307048, 2027.7465316835396, 2061.989690051555, 133588.0, 134396.0, 137369.0, 138580.30709846388, 138742.0, 19065.90467047656, 23014.19450319231, 23544.411389715395, 24435.511289774462, 24465.0, 37370.06574835651, 37425.62574374222, 37672.43491347632, 38114.23771524554, 38401.0, 11211.831822522669, 11522.135839812048, 11531.250468719674, 11679.0, 11859.525618975316, 9360.017198194228, 9374.765630859147, 9392.436453812794, 9537.523123843781, 9555.378900371592, 9109.908900910907, 9455.29085318604, 10928.289661172157, 11161.609343673095, 11264.267822591506, 4052.9664935441865, 4446.755428451402, 4498.145352383037, 4522.366868638327, 4524.23088075028, 791.6160662078887, 802.7757669553977, 822.8560001999626, 915.514777168102, 960.6205548808182, 18107.909460452876, 22424.260549690774, 22742.65886011711, 22785.544289114125, 22819.657705134436, 965.8068386322764, 981.3964411886781, 1003.2926786615565, 1008.8789345278614, 1010.0707349238718, 11346.886531134583, 11886.881131188751, 12076.939615301868, 12077.0, 12132.575359862363, 716791.0, 729250.0, 747943.0, 761543.1922840461, 785291.0, 125820.0, 127879.0, 128212.71787282008, 152095.43713688606, 178064.0, 1306.3794697518792, 1320.7688654485426, 1365.549368708324, 1370.1984339161502, 1371.4102935737728, 19265.22939082421, 20571.280005199747, 20700.792992069888, 20734.585308294052, 20848.0, 53364.73317633542, 66024.66987665031, 66532.00201996877, 66681.33318666751, 67236.66381668061, 69548.0, 83001.58499207466, 94440.52779736332, 95303.52348238214, 97764.0, 10085.848712269175, 11003.0, 11597.362145082101, 11924.344161071225, 12005.0, 10274.691759247235, 12372.948299394653, 13100.148490348098, 13292.534761283308, 13365.866341336463, 9473.715788526355, 11156.330620162817, 11168.0, 11190.94404527972, 11551.942240288745, 8323.167683231628, 8325.0, 9712.5143742814, 9924.851127232952, 9990.700278991642, 11024.559017639205, 11555.537778488766, 11713.355765432983, 11807.881921181022, 11860.169788115094, 8894.955525222333, 11583.0, 11641.0, 11644.35956022393, 11948.0, 11118.888811111785, 11277.887221127683, 11527.769444610893, 11555.884441155817, 11611.6516504505], "value": 11758.608601472291, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1520.414640363461, 1581.9321957678571, 1592.6098105964038, 1597.233328002558, 1601.4875239923226, 11014.0, 11185.272957257772, 11584.362860042695, 11698.94709476147, 11699.707507312321, 11124.443777811097, 11287.943560282187, 11288.774224515526, 11290.491927863246, 11295.265807722491, 2424.9272521824328, 2471.122751423246, 2472.874841946919, 2472.950540989179, 2476.158106243877, 2336.894839732208, 2353.186172428159, 2368.8933997970134, 2373.2880135959144, 2377.1442280778906, 2030.7055476955832, 2376.3821406434363, 2433.793127584149, 2445.8654773987423, 2456.4718585504115, 2359.9646005309764, 2416.32342943976, 2423.333583264599, 2424.709034915804, 2433.197044975154, 2395.904163833462, 2416.5287768885073, 2417.468157005456, 2419.794317483016, 2421.4551725861716, 11043.72390690231, 11235.157363197794, 11241.775164496747, 11286.209965302432, 11311.943440282746, 5836.095405212156, 5989.610675306091, 6024.096385542204, 6035.275766907956, 6051.031834906394, 3499.7550171488006, 3610.981945090284, 3611.5846677632144, 3653.561572611277, 3655.232401195752, 1197.8143387774821, 1206.6741979665528, 1233.1306428967646, 1255.4727014653868, 1257.905657075723, 12245.755084898252, 13314.001449891297, 13515.918726501855, 15240.923795381063, 15298.617534561614, 4734.573888349996, 4843.6367272454, 4860.878478038077, 4869.902601947942, 4879.731614761151, 11904.047676186057, 12032.157748957665, 12058.758824823455, 12061.638150855488, 12095.092868034933, 5873.236479257669, 5878.0, 5903.793367232126, 5909.970450147722, 6000.459958603747, 5224.764885580082, 5387.568994480433, 5422.75597598109, 5422.810201642924, 5464.28964234647, 3814.0, 3984.203159368138, 3987.5613682494904, 4016.397540368968, 4040.797960102042, 1020.321486211661, 1021.9693409197736, 1032.2670903658336, 1039.3348257115451, 1040.635777477892, 942.3497786527274, 951.8001219743793, 973.6154219083422, 974.1622204903778, 1017.2421545948316, 6316.431521163117, 6649.501287403464, 6700.430463410665, 6730.899036514456, 6917.342852429011, 936.2603543200931, 952.1716106986827, 989.7183147823586, 1018.9337693049928, 1030.5311083457009, 4038.1923615276473, 4278.957210427856, 4286.292761694285, 4461.821527138943, 4484.820607175741, 13336.266505342299, 14158.787618185735, 14178.14931104137, 14207.0, 14226.786598201035, 13363.198208107544, 13589.048766586442, 13798.448062077405, 13845.861541384456, 13857.722845543234, 8594.398392112493, 8841.248493878093, 9844.212463002943, 10090.646827361006, 10202.387856728472, 6532.41208291237, 6586.242582103119, 6600.306967768507, 6621.602703837785, 6719.563228390139, 12264.938675306566, 12418.875811242133, 12482.188657737219, 12604.810927835906, 12652.0, 11289.435528223721, 12219.57231496929, 12514.499420023098, 12594.0, 12767.297798621164, 7971.202879711982, 8088.0, 8205.79485512867, 8211.630476628447, 8213.137620549756, 10340.793184136426, 10353.689389318333, 10363.32638378503, 10426.68719938403, 10465.738356541147, 8580.65677372898, 8735.956320218358, 8890.511021893599, 8918.063603321818, 9000.23498002651, 8561.272291855013, 8615.526146062026, 8707.782305442415, 8722.389432739647, 8834.690785822466, 7387.704491820482, 7634.236576342321, 7798.064232292161, 7821.726239581587, 7905.960470197612, 4830.975845120752, 5760.0783874579865, 5832.965090587695, 5969.820905372846, 6072.514198864169, 5850.2102216200265, 5907.49786268172, 5991.430814072656, 6080.422359875804, 6096.0], "value": 5156.908805943159, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.78909130882306, 355.3798621405647, 373.0840785870687, 377.40935436042577, 391.6279534442279, 4728.787204575794, 4836.951630483699, 4933.7286449245275, 5157.277981082647, 5243.816466423676, 5092.0, 5113.846584602458, 5122.769475373608, 5153.0, 5174.327337446136, 3658.926821463569, 3905.882823515285, 4060.309747342959, 4062.420363069443, 4298.860801887495, 3501.772384794993, 3644.489771432002, 3794.886153415388, 3851.4222866569958, 4299.53815702661, 3699.870504532342, 4189.874303770892, 4321.903876619836, 4336.677313419405, 4346.78266086696, 3357.4891298915586, 4069.593040695907, 4228.6617070634275, 4305.74165550068, 4317.568243175689, 3374.7975121492545, 3413.6927676508985, 3638.399664055428, 3787.867424640124, 3957.0, 5026.220935754964, 5072.949270507284, 5081.856582268997, 5090.516400941905, 5095.79616815327, 4691.929621055687, 4945.727984960826, 4976.62675299354, 5032.169692000815, 5032.396112466491, 4941.975290123562, 4970.627202959757, 5470.79642478654, 5538.418466061086, 5575.8606034849045, 3987.501562304712, 4155.10665206982, 4448.4439445069365, 4473.977630111829, 4488.685791994561, 5047.570956468705, 5198.064348417283, 5461.754221060062, 5550.528205102531, 5900.586958912878, 3880.3403421418425, 4016.9397459038137, 4074.959250407458, 4143.881152088919, 4481.35020422037, 4244.0, 4457.621102206284, 4600.746958917225, 4601.608863246594, 4610.9510086455275, 4546.65900057497, 4792.9281060784115, 4957.479464656231, 4985.332846926876, 5031.748412579356, 4505.324201369821, 4887.242477415935, 5219.9038201977255, 5345.518903298722, 5451.0, 1369.7877378520081, 1388.6181300142541, 1434.7202295552268, 1528.465037236958, 1535.8924875258624, 1107.3189988157387, 1109.778044391125, 1111.90548803351, 1180.8110702287593, 1190.7142285851507, 4405.449318835145, 5034.295198672189, 5063.797448102108, 5088.872778180576, 5099.413567439792, 5059.822906198266, 5143.328418264079, 5669.31968163823, 5676.602637815313, 5733.483986441241, 4548.4541854977615, 4992.450830408652, 5046.243063540498, 5100.744962751847, 5117.590592752571, 2837.4183292425005, 2952.995981366346, 2956.8669409876607, 2974.7917645765024, 2995.8951436699613, 5200.777817212952, 5246.0, 5259.422173348057, 5618.942951343742, 5934.228550288522, 4783.6651434399255, 5138.946515964219, 5625.774969001194, 5743.62666426681, 5874.882502350015, 5121.846344609742, 5577.6932268724795, 5788.450097240755, 5806.2451881255165, 6033.698315084141, 5543.918935807479, 5586.357568879631, 5792.652440853562, 5809.651420914759, 5819.70901454934, 4909.582685471775, 5428.918566221589, 5584.972075139598, 5644.491995720405, 5685.573581981451, 5825.660098177358, 5906.064405272674, 6001.909971350346, 6024.1806554756895, 6027.397260273938, 3963.781991990468, 4006.238814625212, 4193.182329445729, 4364.996050908246, 4539.752822352956, 4894.388201474816, 5044.571211447068, 5445.700486473282, 5582.413846546055, 5768.596198266081, 5059.494050594911, 5149.304843846033, 5293.855988324734, 5487.890242195213, 5567.331920169606, 4908.361912951294, 4920.597629675525, 5068.29043933857, 5195.922061169161, 5651.660900345993, 4648.511906249931, 5206.271122042841, 5247.897941432189, 5416.918746218731, 5480.780283984993, 4871.975640121776, 5202.25724382321, 5575.386707462257, 5578.219049333015, 5610.551155907436, 4554.772261386984, 5016.8610896010205, 5201.505856943584, 5424.755885985219, 5503.807366742145], "value": 4176.153251890466, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.2323866984985, 748.8090536913087, 764.9847003059939, 766.1801871996965, 788.1251810490359, 15218.467353642625, 16015.759763603555, 16459.012459252437, 16996.575085622866, 17075.658486830256, 13781.242031688253, 15446.304916278767, 15866.682666346665, 15917.761233581505, 15927.283272252747, 944.3578366710634, 967.6226271754005, 993.3791380387258, 997.436448406648, 998.8301988661945, 874.9850173798395, 934.3552948465536, 991.2862738828069, 995.3778888194878, 996.093554865073, 1029.1560920045565, 1035.4977835749655, 1039.142707266508, 1040.6513817870984, 1040.9167266618651, 1035.435687550287, 1037.0873631204568, 1038.7195457226544, 1038.7506998320387, 1039.2413538117216, 766.513264077315, 769.4306213402098, 769.5767327969594, 769.5959621198859, 770.0528350129316, 16467.0, 16908.154592270337, 16940.407085752064, 17439.912800436043, 17684.46946591604, 14118.082324648865, 14427.78358324626, 14510.782338264935, 14577.052491588012, 14621.0496317739, 9863.80272394548, 11482.942585287105, 11756.966214296828, 11760.17678762487, 12003.819942700866, 3763.9059023524355, 3879.573246942834, 3906.277338692351, 3906.7655940643654, 3907.27715372657, 62563.0, 71664.92502612412, 100814.49592752062, 101086.4945675267, 104491.47754261256, 11086.390248536247, 11190.32858028521, 11265.380404077854, 11316.98147166759, 12090.879091209152, 22768.316950491513, 23005.0, 23005.539889202122, 23026.309210723706, 23042.884785575963, 7927.484713493604, 7995.0, 8045.919540804517, 8148.981377327834, 8282.668693252323, 4397.538258482813, 4398.824047038147, 4427.800748966304, 4476.6418686505, 4506.59440650343, 3396.4735466002808, 3435.450327947566, 3469.9653003469643, 3474.3225071111397, 3477.2176260341726, 599.1282683695213, 601.9668918209453, 605.8515663662431, 606.848287928018, 609.0985341694317, 686.3308274432388, 691.7578847403369, 701.2371257246433, 721.195866608738, 724.8803947348629, 9392.685024096496, 9576.76058098553, 10478.0, 10656.20078494116, 10800.78398432027, 631.3307893632764, 640.6476437959104, 678.3657280442703, 697.330562659842, 697.4838619421591, 4975.514785493648, 5045.823396181116, 5052.444231134572, 5054.848354549369, 5061.822836200715, 89839.10160898438, 107345.0, 107584.0, 108471.0, 109258.0, 57826.7108664454, 62290.68854655698, 83540.0, 86694.56652716696, 91344.54327728452, 53076.73461632667, 74295.0, 77840.83238751424, 78190.60904695553, 78825.60587197142, 10982.945085274523, 11143.762202653652, 11254.88745112571, 11334.489947952197, 11380.260283081574, 42708.57291427046, 47938.56184314476, 49666.50333496619, 49819.25632602986, 50012.0, 62439.063414047916, 63082.0, 63902.36097638964, 64828.0275795854, 65254.67372663107, 11017.944910275397, 11284.661460156209, 11360.03439707634, 12136.39318034077, 12879.291638959947, 14611.92694036523, 14655.047421917541, 14886.404543818126, 15170.393184272505, 15399.923000384926, 11973.10201734856, 12305.815412768994, 13723.313834308045, 14315.14109153454, 15018.94867359318, 11438.42807859587, 12401.813972790596, 13750.312484375941, 14032.579022629337, 14278.000539962102, 13169.868301316865, 13238.669033274246, 13447.462101516221, 13675.179489230679, 13707.383167757675, 9592.142384452141, 9725.247277450351, 9740.853887191555, 10106.14097801695, 10217.59129634836, 9432.52837358143, 9470.0, 11266.66200014001, 11410.258333208314, 11495.942520287344], "value": 7630.357136390586, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1525.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 142.0, 120.0, 120.0, 120.0, 121.0, 118.0, 119.0, 119.0, 118.0], "value": 121.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 128.0, 142.0, 140.0, 145.0, 146.0, 147.0, 116.0, 131.0, 148.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 201.0, 196.0, 199.0, 202.0, 197.0, 191.0, 196.0, 206.0, 199.0], "value": 198.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 61.0, 60.0, 91.0, 63.0, 63.0, 64.0, 62.0, 77.0, 61.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 88.0, 102.0, 103.0, 80.0, 81.0, 73.0, 71.0, 82.0], "value": 83.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 87.0, 93.0, 92.0, 88.0, 88.0, 87.0, 89.0, 87.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 99.0, 96.0, 106.0, 95.0, 95.0, 96.0, 97.0, 95.0, 116.0], "value": 99.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 91.0, 90.0, 90.0, 89.0, 88.0, 81.0, 139.0, 146.0], "value": 99.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 68.0, 71.0, 70.0, 68.0, 68.0, 69.0, 70.0], "value": 68.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 55.0, 58.0, 63.0, 61.0, 74.0, 81.0, 82.0, 82.0, 57.0], "value": 67.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 98.0, 89.0, 82.0, 86.0, 86.0, 86.0, 83.0, 92.0, 93.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 138.0, 136.0, 137.0, 139.0, 137.0, 147.0, 156.0, 195.0, 149.0], "value": 147.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 167.0, 230.0, 202.0, 167.0, 166.0, 169.0, 169.0, 170.0, 170.0], "value": 181.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 74.0, 85.0, 88.0, 88.0, 85.0, 67.0, 65.0, 66.0, 66.0], "value": 74.0, "unit": "score"}]}]} 00:36:05 INFO - Return code: 0 00:36:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:36:05 ERROR - # TBPL SUCCESS # 00:36:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:36:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:36:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:36:05 INFO - 2017-05-23 00:36:05.205 screenresolution[1927:12216] starting screenresolution argv=screenresolution get 00:36:05 INFO - 2017-05-23 00:36:05.208 screenresolution[1927:12216] Display 0: 1600x1200x32@60 00:36:05 INFO - 2017-05-23 00:36:05.215 screenresolution[1928:12219] starting screenresolution argv=screenresolution list 00:36:05 INFO - Available Modes on Display 0 00:36:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:36:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:36:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:36:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:36:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:36:05 INFO - Intel Iris: 00:36:05 INFO - Chipset Model: Intel Iris 00:36:05 INFO - Type: GPU 00:36:05 INFO - Bus: Built-In 00:36:05 INFO - VRAM (Dynamic, Max): 1536 MB 00:36:05 INFO - Vendor: Intel (0x8086) 00:36:05 INFO - Device ID: 0x0a2e 00:36:05 INFO - Revision ID: 0x0009 00:36:05 INFO - Displays: 00:36:05 INFO - Display: 00:36:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:36:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:36:05 INFO - Display Serial Number: 1600x1200 60 00:36:05 INFO - Main Display: Yes 00:36:05 INFO - Mirror: Off 00:36:05 INFO - Online: Yes 00:36:05 INFO - Rotation: Supported 00:36:05 INFO - Return code: 0 00:36:05 INFO - Running post-action listener: _resource_record_post_action 00:36:05 INFO - [mozharness: 2017-05-23 07:36:05.466700Z] Finished run-tests step (success) 00:36:05 INFO - Running post-run listener: _resource_record_post_run 00:36:05 INFO - Total resource usage - Wall time: 1197s; CPU: 55.0%; Read bytes: 35885056; Write bytes: 515053568; Read time: 334; Write time: 2004 00:36:05 INFO - TinderboxPrint: CPU usage
54.5% 00:36:05 INFO - TinderboxPrint: I/O read bytes / time
35,885,056 / 334 00:36:05 INFO - TinderboxPrint: I/O write bytes / time
515,053,568 / 2,004 00:36:05 INFO - TinderboxPrint: CPU idle
2,178.4 (45.5%) 00:36:05 INFO - TinderboxPrint: CPU system
958.5 (20.0%) 00:36:05 INFO - TinderboxPrint: CPU user
1,652.8 (34.5%) 00:36:05 INFO - TinderboxPrint: Swap in / out
240,807,936 / 0 00:36:05 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209500672; Write bytes: 200478720; Read time: 15905; Write time: 351 00:36:05 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 30752768; Write bytes: 304367616; Read time: 297; Write time: 1589 00:36:05 INFO - Running post-run listener: _upload_blobber_files 00:36:05 INFO - Blob upload gear active. 00:36:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:36:05 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 00:36:05 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', 'Jamun', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:36:05 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 Jamun -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:36:06 INFO - (blobuploader) - INFO - Open directory for files ... 00:36:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:36:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:36:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:36:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:36:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:36:07 INFO - (blobuploader) - INFO - Done attempting. 00:36:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:36:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:36:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:36:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:36:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:36:08 INFO - (blobuploader) - INFO - Done attempting. 00:36:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 00:36:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:36:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:36:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 00:36:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:36:09 INFO - (blobuploader) - INFO - Done attempting. 00:36:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:36:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:36:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:36:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:36:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:36:11 INFO - (blobuploader) - INFO - Done attempting. 00:36:11 INFO - (blobuploader) - INFO - Iteration through files over. 00:36:11 INFO - Return code: 0 00:36:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:36:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:36:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/74b42c80b03480bc244fb5ff674cd30fbabc176c5d46ddeb92590f95f81bf11ccad124526e55213f6aef664da99d332bd0dc89fecd7fd0071df2f2339ef0c8e1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/077a03224f168e538595f0a69383a20051d947753b87149fba44fdb4f06ef0f395fb177e0dc7aac3415f3e35f1bc41267642e47c15631617b0712d89eb37a624", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/9bed4312dff0f1d1c43717c929033c03f123f56c842e499c9547c528fcb12b3b6656a8a628d77f249531a6144bfc02f98d20bd2d076b8078b198ae3760878ca8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/2487771badcddaf8be4231ecd029bce18b6d3c89e63c9afe562046aae733d2ce41bd66902774c0356e051bd9acb7afd8ef208901f655db4b5a9a34a328dc08f3"} 00:36:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:36:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:36:11 INFO - Contents: 00:36:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/74b42c80b03480bc244fb5ff674cd30fbabc176c5d46ddeb92590f95f81bf11ccad124526e55213f6aef664da99d332bd0dc89fecd7fd0071df2f2339ef0c8e1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/077a03224f168e538595f0a69383a20051d947753b87149fba44fdb4f06ef0f395fb177e0dc7aac3415f3e35f1bc41267642e47c15631617b0712d89eb37a624", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/9bed4312dff0f1d1c43717c929033c03f123f56c842e499c9547c528fcb12b3b6656a8a628d77f249531a6144bfc02f98d20bd2d076b8078b198ae3760878ca8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/2487771badcddaf8be4231ecd029bce18b6d3c89e63c9afe562046aae733d2ce41bd66902774c0356e051bd9acb7afd8ef208901f655db4b5a9a34a328dc08f3"} 00:36:11 INFO - Running post-run listener: copy_logs_to_upload_dir 00:36:11 INFO - Copying logs to upload dir... 00:36:11 INFO - mkdir: /builds/slave/test/build/upload/logs 00:36:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=2482.890490 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 22 secs) (at 2017-05-23 00:36:11.767659) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 00:36:11.770931) ========= 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.ntl8dknAqx/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.cvRki42vIU/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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/Jamun/sha512/74b42c80b03480bc244fb5ff674cd30fbabc176c5d46ddeb92590f95f81bf11ccad124526e55213f6aef664da99d332bd0dc89fecd7fd0071df2f2339ef0c8e1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/077a03224f168e538595f0a69383a20051d947753b87149fba44fdb4f06ef0f395fb177e0dc7aac3415f3e35f1bc41267642e47c15631617b0712d89eb37a624", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/9bed4312dff0f1d1c43717c929033c03f123f56c842e499c9547c528fcb12b3b6656a8a628d77f249531a6144bfc02f98d20bd2d076b8078b198ae3760878ca8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/2487771badcddaf8be4231ecd029bce18b6d3c89e63c9afe562046aae733d2ce41bd66902774c0356e051bd9acb7afd8ef208901f655db4b5a9a34a328dc08f3"} build_url:https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011369 build_url: 'https://queue.taskcluster.net/v1/task/TYGuttLnQFGxqYVeEf7gvQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/74b42c80b03480bc244fb5ff674cd30fbabc176c5d46ddeb92590f95f81bf11ccad124526e55213f6aef664da99d332bd0dc89fecd7fd0071df2f2339ef0c8e1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/077a03224f168e538595f0a69383a20051d947753b87149fba44fdb4f06ef0f395fb177e0dc7aac3415f3e35f1bc41267642e47c15631617b0712d89eb37a624", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/9bed4312dff0f1d1c43717c929033c03f123f56c842e499c9547c528fcb12b3b6656a8a628d77f249531a6144bfc02f98d20bd2d076b8078b198ae3760878ca8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/2487771badcddaf8be4231ecd029bce18b6d3c89e63c9afe562046aae733d2ce41bd66902774c0356e051bd9acb7afd8ef208901f655db4b5a9a34a328dc08f3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 00:36:11.804803) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 00:36:11.805161) ========= 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.ntl8dknAqx/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.cvRki42vIU/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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.005315 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 00:36:11.854848) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 00:36:11.855290) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 00:36:11.855846) ========= ========= Total master_lag: 0.18 =========