builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-052 starttime: 1506294685.12 results: success (0) revision: d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-24 16:11:25.120926) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-24 16:11:25.121373) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-24 16:11:25.121907) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-24 16:11:25.211729) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:11:25.215087) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-24 16:11:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (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% 220M=0s 2017-09-24 16:11:25 (220 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126108 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:11:25.386805) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:11:25.387081) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019364 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:11:25.457990) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:11:25.458325) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-24 16:11:25,405 truncating revision to first 12 chars 2017-09-24 16:11:25,405 Setting DEBUG logging. 2017-09-24 16:11:25,405 attempt 1/10 2017-09-24 16:11:25,405 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d9cfaad3b11e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-24 16:11:25,799 unpacking tar archive at: mozilla-central-d9cfaad3b11e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.718875 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:11:26.206861) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-24 16:11:26.207176) ========= script_repo_revision: d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-24 16:11:26.207549) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-24 16:11:26.207832) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-24 16:11:26.246052) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-24 16:11:26.246344) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:11:26 INFO - MultiFileLogger online at 20170924 16:11:26 in /builds/slave/test 16:11:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:11:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:11:26 INFO - {'append_to_log': False, 16:11:26 INFO - 'base_work_dir': '/builds/slave/test', 16:11:26 INFO - 'blob_upload_branch': 'Firefox', 16:11:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:11:26 INFO - 'branch': 'Firefox', 16:11:26 INFO - 'buildbot_json_path': 'buildprops.json', 16:11:26 INFO - 'code_coverage': False, 16:11:26 INFO - 'config_files': ('talos/linux_config.py',), 16:11:26 INFO - 'default_actions': ('clobber', 16:11:26 INFO - 'read-buildbot-config', 16:11:26 INFO - 'download-and-extract', 16:11:26 INFO - 'populate-webroot', 16:11:26 INFO - 'create-virtualenv', 16:11:26 INFO - 'install', 16:11:26 INFO - 'setup-mitmproxy', 16:11:26 INFO - 'run-tests'), 16:11:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:11:26 INFO - 'disable_ccov_upload': False, 16:11:26 INFO - 'disable_stylo': False, 16:11:26 INFO - 'download_minidump_stackwalk': True, 16:11:26 INFO - 'download_symbols': 'ondemand', 16:11:26 INFO - 'enable_stylo': False, 16:11:26 INFO - 'enable_webrender': False, 16:11:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:11:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:11:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:11:26 INFO - 'gecko_profile': False, 16:11:26 INFO - 'gecko_profile_interval': 0, 16:11:26 INFO - 'installer_path': 'installer.exe', 16:11:26 INFO - 'log_level': 'info', 16:11:26 INFO - 'log_name': 'talos', 16:11:26 INFO - 'log_to_console': True, 16:11:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:11:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:11:26 INFO - 'opt_config_files': (), 16:11:26 INFO - 'pip_index': False, 16:11:26 INFO - 'suite': 'dromaeojs-e10s', 16:11:26 INFO - 'system_bits': '32', 16:11:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:11:26 INFO - 'title': 'talos-linux64-ix-052', 16:11:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:11:26 INFO - 'use_talos_json': True, 16:11:26 INFO - 'verify': 'False', 16:11:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:11:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:11:26 INFO - 'work_dir': 'build'} 16:11:26 INFO - [mozharness: 2017-09-24 23:11:26.328398Z] Running clobber step. 16:11:26 INFO - Running pre-action listener: _resource_record_pre_action 16:11:26 INFO - Running main action method: clobber 16:11:26 INFO - rmtree: /builds/slave/test/build 16:11:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:11:27 INFO - Running post-action listener: _resource_record_post_action 16:11:27 INFO - [mozharness: 2017-09-24 23:11:27.838917Z] Finished clobber step (success) 16:11:27 INFO - [mozharness: 2017-09-24 23:11:27.839029Z] Running read-buildbot-config step. 16:11:27 INFO - Running pre-action listener: _resource_record_pre_action 16:11:27 INFO - Running main action method: read_buildbot_config 16:11:27 INFO - Using buildbot properties: 16:11:27 INFO - { 16:11:27 INFO - "project": "", 16:11:27 INFO - "product": "firefox", 16:11:27 INFO - "who": "archaeopteryx@coole-files.de", 16:11:27 INFO - "installer_path": "public/build/target.tar.bz2", 16:11:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:11:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:11:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 16:11:27 INFO - "stage_platform": "linux64-pgo", 16:11:27 INFO - "basedir": "/builds/slave/test", 16:11:27 INFO - "buildnumber": 205, 16:11:27 INFO - "platform": "ubuntu64_hw", 16:11:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:11:27 INFO - "slavebuilddir": "test", 16:11:27 INFO - "taskId": "H3GnAIxOTdyLuWbW6mLo6w", 16:11:27 INFO - "branch": "mozilla-central", 16:11:27 INFO - "script_repo_revision": "production", 16:11:27 INFO - "revision": "d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4", 16:11:27 INFO - "slavename": "talos-linux64-ix-052", 16:11:27 INFO - "repo_path": "mozilla-central" 16:11:27 INFO - } 16:11:27 INFO - Finding installer, test and symbols from parent task. 16:11:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H3GnAIxOTdyLuWbW6mLo6w'}, attempt #1 16:11:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H3GnAIxOTdyLuWbW6mLo6w'}, attempt #1 16:11:28 INFO - Task dependencies: ce0ldRR8TaGxVM0-hHQ4zg 16:11:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg'}, attempt #1 16:11:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.tar.bz2 16:11:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.test_packages.json 16:11:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.crashreporter-symbols.zip 16:11:29 INFO - Running post-action listener: _resource_record_post_action 16:11:29 INFO - [mozharness: 2017-09-24 23:11:29.156966Z] Finished read-buildbot-config step (success) 16:11:29 INFO - [mozharness: 2017-09-24 23:11:29.157185Z] Running download-and-extract step. 16:11:29 INFO - Running pre-action listener: _resource_record_pre_action 16:11:29 INFO - Running main action method: download_and_extract 16:11:29 INFO - mkdir: /builds/slave/test/build/tests 16:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:29 INFO - trying https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.test_packages.json 16:11:29 INFO - Downloading https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:11:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:11:29 INFO - Downloaded 989 bytes. 16:11:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:11:29 INFO - Using the following test package requirements: 16:11:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:11:29 INFO - u'common': [u'target.common.tests.zip'], 16:11:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:11:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:11:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:11:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:11:29 INFO - u'mozbase': [u'target.common.tests.zip'], 16:11:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:11:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:11:29 INFO - u'web-platform': [u'target.common.tests.zip', 16:11:29 INFO - u'target.web-platform.tests.tar.gz'], 16:11:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:11:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 16:11:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.common.tests.zip 16:11:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:11:29 INFO - Fetch https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.common.tests.zip into memory 16:11:32 INFO - Content-Length response header: 55346500 16:11:32 INFO - Bytes received: 55346500 16:11:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.talos.tests.zip 16:11:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:11:43 INFO - Fetch https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.talos.tests.zip into memory 16:11:44 INFO - Content-Length response header: 14513823 16:11:44 INFO - Bytes received: 14513823 16:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:44 INFO - trying https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.tar.bz2 16:11:44 INFO - Downloading https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:11:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:11:48 INFO - Downloaded 62421213 bytes. 16:11:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.tar.bz2 16:11:48 INFO - mkdir: /builds/slave/test/properties 16:11:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:11:48 INFO - Writing to file /builds/slave/test/properties/build_url 16:11:48 INFO - Contents: 16:11:48 INFO - build_url:https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.tar.bz2 16:11:48 INFO - Running post-action listener: _resource_record_post_action 16:11:48 INFO - Running post-action listener: find_tests_for_verification 16:11:48 INFO - Running post-action listener: set_extra_try_arguments 16:11:48 INFO - [mozharness: 2017-09-24 23:11:48.499470Z] Finished download-and-extract step (success) 16:11:48 INFO - [mozharness: 2017-09-24 23:11:48.499700Z] Running populate-webroot step. 16:11:48 INFO - Running pre-action listener: _resource_record_pre_action 16:11:48 INFO - Running main action method: populate_webroot 16:11:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:11:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:11:48 INFO - 'tests': ['tresize', 16:11:48 INFO - 'tcanvasmark']}, 16:11:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:11:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:11:48 INFO - 'tests': ['dromaeo_css', 16:11:48 INFO - 'kraken']}, 16:11:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:11:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:11:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:11:48 INFO - 'talos_options': ['--disable-stylo'], 16:11:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:11:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:11:48 INFO - 'tests': ['damp', 'tps']}, 16:11:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:11:48 INFO - 'talos_options': ['--disable-stylo'], 16:11:48 INFO - 'tests': ['damp', 'tps']}, 16:11:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:11:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:11:48 INFO - 'tests': ['dromaeo_dom']}, 16:11:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:11:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:11:48 INFO - 'tests': ['basic_compositor_video', 16:11:48 INFO - 'glvideo']}, 16:11:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:11:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:11:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:11:48 INFO - 'talos_options': ['--disable-stylo'], 16:11:48 INFO - 'tests': ['ts_paint_webext', 16:11:48 INFO - 'tp5o_webext']}, 16:11:48 INFO - 'other-e10s': {'tests': ['a11yr', 16:11:48 INFO - 'ts_paint', 16:11:48 INFO - 'tpaint', 16:11:48 INFO - 'sessionrestore', 16:11:48 INFO - 'sessionrestore_many_windows', 16:11:48 INFO - 'sessionrestore_no_auto_restore', 16:11:48 INFO - 'tabpaint']}, 16:11:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:11:48 INFO - 'tests': ['a11yr', 16:11:48 INFO - 'ts_paint', 16:11:48 INFO - 'tpaint', 16:11:48 INFO - 'sessionrestore', 16:11:48 INFO - 'sessionrestore_many_windows', 16:11:48 INFO - 'sessionrestore_no_auto_restore', 16:11:48 INFO - 'tabpaint']}, 16:11:48 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 16:11:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 16:11:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:11:48 INFO - 'tests': ['perf_reftest_singletons']}, 16:11:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:11:48 INFO - 'tests': ['perf_reftest']}, 16:11:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:11:48 INFO - 'tsvgr_opacity', 16:11:48 INFO - 'tart', 16:11:48 INFO - 'tscrollx', 16:11:48 INFO - 'tsvg_static']}, 16:11:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:11:48 INFO - 'tests': ['tsvgx', 16:11:48 INFO - 'tsvgr_opacity', 16:11:48 INFO - 'tart', 16:11:48 INFO - 'tscrollx', 16:11:48 INFO - 'tsvg_static']}, 16:11:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:11:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:11:48 INFO - 'talos_options': ['--disable-stylo'], 16:11:48 INFO - 'tests': ['tp5o']}, 16:11:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:11:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:11:48 INFO - 'talos_options': ['--mitmproxy', 16:11:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:11:48 INFO - '--firstNonBlankPaint'], 16:11:48 INFO - 'tests': ['tp6_google', 16:11:48 INFO - 'tp6_youtube', 16:11:48 INFO - 'tp6_amazon', 16:11:48 INFO - 'tp6_facebook']}, 16:11:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:11:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:11:48 INFO - 'talos_options': ['--disable-stylo', 16:11:48 INFO - '--mitmproxy', 16:11:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:11:48 INFO - '--firstNonBlankPaint'], 16:11:48 INFO - 'tests': ['tp6_google', 16:11:48 INFO - 'tp6_youtube', 16:11:48 INFO - 'tp6_amazon', 16:11:48 INFO - 'tp6_facebook']}, 16:11:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:11:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:11:48 INFO - 'talos_options': ['--stylo-threads=1', 16:11:48 INFO - '--mitmproxy', 16:11:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:11:48 INFO - '--firstNonBlankPaint'], 16:11:48 INFO - 'tests': ['tp6_google', 16:11:48 INFO - 'tp6_youtube', 16:11:48 INFO - 'tp6_amazon', 16:11:48 INFO - 'tp6_facebook']}, 16:11:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:11:48 INFO - 'talos_options': ['--xperf_path', 16:11:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:11:48 INFO - 'tests': ['tp5n']}, 16:11:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:11:48 INFO - 'talos_options': ['--disable-stylo', 16:11:48 INFO - '--xperf_path', 16:11:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:11:48 INFO - 'tests': ['tp5n']}}} 16:11:48 INFO - Running post-action listener: _resource_record_post_action 16:11:48 INFO - [mozharness: 2017-09-24 23:11:48.519868Z] Finished populate-webroot step (success) 16:11:48 INFO - [mozharness: 2017-09-24 23:11:48.519958Z] Running create-virtualenv step. 16:11:48 INFO - Running pre-action listener: _resource_record_pre_action 16:11:48 INFO - Running main action method: create_virtualenv 16:11:48 INFO - Creating virtualenv /builds/slave/test/build/venv 16:11:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:11:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:11:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:11:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:11:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:11:49 INFO - Installing distribute.............................................................................................................................................................................................done. 16:11:49 INFO - Installing pip...............done. 16:11:49 INFO - Return code: 0 16:11:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:11:49 INFO - Reading from file tmpfile_stdout 16:11:49 INFO - Output received: 16:11:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:11:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11: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/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 0x226c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2434b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d6d60>, '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 0x25d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d7ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:11:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:11:49 INFO - Using env: {'DISPLAY': ':0', 16:11:49 INFO - 'HOME': '/home/cltbld', 16:11:49 INFO - 'LANG': 'en_US.UTF-8', 16:11:49 INFO - 'LANGUAGE': 'en_US:en', 16:11:49 INFO - 'LOGNAME': 'cltbld', 16:11:49 INFO - 'MAIL': '/var/mail/cltbld', 16:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:49 INFO - 'MOZ_NO_REMOTE': '1', 16:11:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:11:49 INFO - 'NO_EM_RESTART': '1', 16:11:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:49 INFO - 'PWD': '/builds/slave/test', 16:11:49 INFO - 'SHELL': '/bin/bash', 16:11:49 INFO - 'SHLVL': '1', 16:11:49 INFO - 'TERM': 'linux', 16:11:49 INFO - 'TMOUT': '86400', 16:11:49 INFO - 'USER': 'cltbld', 16:11:49 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 16:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:49 INFO - '_': '/tools/buildbot/bin/python'} 16:11:51 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:11:51 INFO - Downloading/unpacking pip>=1.5 16:11:51 INFO - Running setup.py egg_info for package pip 16:11:51 INFO - warning: no files found matching 'pip/cacert.pem' 16:11:51 INFO - warning: no files found matching '*.html' under directory 'docs' 16:11:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:11:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:11:51 INFO - Installing collected packages: pip 16:11:51 INFO - Found existing installation: pip 1.1 16:11:51 INFO - Uninstalling pip: 16:11:51 INFO - Successfully uninstalled pip 16:11:51 INFO - Running setup.py install for pip 16:11:51 INFO - warning: no files found matching 'pip/cacert.pem' 16:11:51 INFO - warning: no files found matching '*.html' under directory 'docs' 16:11:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:11:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:11:51 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:11:51 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:11:51 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:11:51 INFO - Successfully installed pip 16:11:51 INFO - Cleaning up... 16:11:51 INFO - Return code: 0 16:11:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:11:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:51 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/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 0x226c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2434b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d6d60>, '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 0x25d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d7ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:11:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:11:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:11:51 INFO - Using env: {'DISPLAY': ':0', 16:11:51 INFO - 'HOME': '/home/cltbld', 16:11:51 INFO - 'LANG': 'en_US.UTF-8', 16:11:51 INFO - 'LANGUAGE': 'en_US:en', 16:11:51 INFO - 'LOGNAME': 'cltbld', 16:11:51 INFO - 'MAIL': '/var/mail/cltbld', 16:11:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:51 INFO - 'MOZ_NO_REMOTE': '1', 16:11:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:11:51 INFO - 'NO_EM_RESTART': '1', 16:11:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:11:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:51 INFO - 'PWD': '/builds/slave/test', 16:11:51 INFO - 'SHELL': '/bin/bash', 16:11:51 INFO - 'SHLVL': '1', 16:11:51 INFO - 'TERM': 'linux', 16:11:51 INFO - 'TMOUT': '86400', 16:11:51 INFO - 'USER': 'cltbld', 16:11:51 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 16:11:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:51 INFO - '_': '/tools/buildbot/bin/python'} 16:11:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:51 INFO - Downloading/unpacking psutil>=3.1.1 16:11:51 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 16:11:51 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 16:11:51 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 16:11:51 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 16:11:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:11:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:11:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:11:53 INFO - Installing collected packages: psutil 16:11:53 INFO - Running setup.py install for psutil 16:11:53 INFO - building 'psutil._psutil_linux' extension 16:11:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:11:53 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:11:53 INFO - building 'psutil._psutil_posix' extension 16:11:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:11:53 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:11:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:11:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:11:53 INFO - Successfully installed psutil 16:11:53 INFO - Cleaning up... 16:11:53 INFO - Return code: 0 16:11:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:53 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/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 0x226c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2434b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d6d60>, '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 0x25d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d7ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:11:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:11:53 INFO - Using env: {'DISPLAY': ':0', 16:11:53 INFO - 'HOME': '/home/cltbld', 16:11:53 INFO - 'LANG': 'en_US.UTF-8', 16:11:53 INFO - 'LANGUAGE': 'en_US:en', 16:11:53 INFO - 'LOGNAME': 'cltbld', 16:11:53 INFO - 'MAIL': '/var/mail/cltbld', 16:11:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:53 INFO - 'MOZ_NO_REMOTE': '1', 16:11:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:11:53 INFO - 'NO_EM_RESTART': '1', 16:11:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:11:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:53 INFO - 'PWD': '/builds/slave/test', 16:11:53 INFO - 'SHELL': '/bin/bash', 16:11:53 INFO - 'SHLVL': '1', 16:11:53 INFO - 'TERM': 'linux', 16:11:53 INFO - 'TMOUT': '86400', 16:11:53 INFO - 'USER': 'cltbld', 16:11:53 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 16:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:53 INFO - '_': '/tools/buildbot/bin/python'} 16:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:11:54 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 16:11:54 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 16:11:54 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 16:11:54 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 16:11:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:11:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:11:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:11:55 INFO - Installing collected packages: mozsystemmonitor 16:11:55 INFO - Running setup.py install for mozsystemmonitor 16:11:55 INFO - Successfully installed mozsystemmonitor 16:11:55 INFO - Cleaning up... 16:11:55 INFO - Return code: 0 16:11:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x226c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2434b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d6d60>, '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 0x25d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d7ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:11:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:11:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:11:55 INFO - Using env: {'DISPLAY': ':0', 16:11:55 INFO - 'HOME': '/home/cltbld', 16:11:55 INFO - 'LANG': 'en_US.UTF-8', 16:11:55 INFO - 'LANGUAGE': 'en_US:en', 16:11:55 INFO - 'LOGNAME': 'cltbld', 16:11:55 INFO - 'MAIL': '/var/mail/cltbld', 16:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:55 INFO - 'MOZ_NO_REMOTE': '1', 16:11:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:11:55 INFO - 'NO_EM_RESTART': '1', 16:11:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:55 INFO - 'PWD': '/builds/slave/test', 16:11:55 INFO - 'SHELL': '/bin/bash', 16:11:55 INFO - 'SHLVL': '1', 16:11:55 INFO - 'TERM': 'linux', 16:11:55 INFO - 'TMOUT': '86400', 16:11:55 INFO - 'USER': 'cltbld', 16:11:55 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 16:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:55 INFO - '_': '/tools/buildbot/bin/python'} 16:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:55 INFO - Downloading/unpacking jsonschema==2.5.1 16:11:55 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 16:11:55 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 16:11:55 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 16:11:55 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 16:11:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:11:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:56 INFO - Downloading functools32-3.2.3-2.tar.gz 16:11:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:11:56 INFO - warning: no files found matching '*.txt' 16:11:56 INFO - no previously-included directories found matching 'build' 16:11:56 INFO - no previously-included directories found matching 'dist' 16:11:56 INFO - no previously-included directories found matching '.git*' 16:11:56 INFO - Installing collected packages: jsonschema, functools32 16:11:56 INFO - Running setup.py install for functools32 16:11:57 INFO - warning: no files found matching '*.txt' 16:11:57 INFO - no previously-included directories found matching 'build' 16:11:57 INFO - no previously-included directories found matching 'dist' 16:11:57 INFO - no previously-included directories found matching '.git*' 16:11:57 INFO - Successfully installed jsonschema functools32 16:11:57 INFO - Cleaning up... 16:11:57 INFO - Return code: 0 16:11:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:57 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/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 0x226c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2434b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d6d60>, '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 0x25d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d7ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:11:57 INFO - Using env: {'DISPLAY': ':0', 16:11:57 INFO - 'HOME': '/home/cltbld', 16:11:57 INFO - 'LANG': 'en_US.UTF-8', 16:11:57 INFO - 'LANGUAGE': 'en_US:en', 16:11:57 INFO - 'LOGNAME': 'cltbld', 16:11:57 INFO - 'MAIL': '/var/mail/cltbld', 16:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:57 INFO - 'MOZ_NO_REMOTE': '1', 16:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:11:57 INFO - 'NO_EM_RESTART': '1', 16:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:57 INFO - 'PWD': '/builds/slave/test', 16:11:57 INFO - 'SHELL': '/bin/bash', 16:11:57 INFO - 'SHLVL': '1', 16:11:57 INFO - 'TERM': 'linux', 16:11:57 INFO - 'TMOUT': '86400', 16:11:57 INFO - 'USER': 'cltbld', 16:11:57 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 16:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:57 INFO - '_': '/tools/buildbot/bin/python'} 16:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:11:57 INFO - Cleaning up... 16:11:57 INFO - Return code: 0 16:11:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:57 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/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 0x226c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2434b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d6d60>, '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 0x25d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d7ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:11:57 INFO - Using env: {'DISPLAY': ':0', 16:11:57 INFO - 'HOME': '/home/cltbld', 16:11:57 INFO - 'LANG': 'en_US.UTF-8', 16:11:57 INFO - 'LANGUAGE': 'en_US:en', 16:11:57 INFO - 'LOGNAME': 'cltbld', 16:11:57 INFO - 'MAIL': '/var/mail/cltbld', 16:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:57 INFO - 'MOZ_NO_REMOTE': '1', 16:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:11:57 INFO - 'NO_EM_RESTART': '1', 16:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:57 INFO - 'PWD': '/builds/slave/test', 16:11:57 INFO - 'SHELL': '/bin/bash', 16:11:57 INFO - 'SHLVL': '1', 16:11:57 INFO - 'TERM': 'linux', 16:11:57 INFO - 'TMOUT': '86400', 16:11:57 INFO - 'USER': 'cltbld', 16:11:57 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 16:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:57 INFO - '_': '/tools/buildbot/bin/python'} 16:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:57 INFO - Downloading/unpacking blobuploader==1.2.4 16:11:57 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 16:11:57 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 16:11:57 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 16:11:57 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 16:11:58 INFO - Downloading blobuploader-1.2.4.tar.gz 16:11:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:11:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:11:58 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 16:11:58 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 16:11:58 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 16:11:58 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 16:11:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:11:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:11:59 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 16:11:59 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 16:11:59 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 16:11:59 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 16:11:59 INFO - Downloading docopt-0.6.1.tar.gz 16:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:11:59 INFO - Installing collected packages: blobuploader, requests, docopt 16:11:59 INFO - Running setup.py install for blobuploader 16:11:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:11:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:11:59 INFO - Running setup.py install for requests 16:11:59 INFO - Running setup.py install for docopt 16:11:59 INFO - Successfully installed blobuploader requests docopt 16:11:59 INFO - Cleaning up... 16:11:59 INFO - Return code: 0 16:11:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:59 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/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 0x226c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2434b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d6d60>, '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 0x25d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d7ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:11:59 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:11:59 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:11:59 INFO - Using env: {'DISPLAY': ':0', 16:11:59 INFO - 'HOME': '/home/cltbld', 16:11:59 INFO - 'LANG': 'en_US.UTF-8', 16:11:59 INFO - 'LANGUAGE': 'en_US:en', 16:11:59 INFO - 'LOGNAME': 'cltbld', 16:11:59 INFO - 'MAIL': '/var/mail/cltbld', 16:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:59 INFO - 'MOZ_NO_REMOTE': '1', 16:11:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:11:59 INFO - 'NO_EM_RESTART': '1', 16:11:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:59 INFO - 'PWD': '/builds/slave/test', 16:11:59 INFO - 'SHELL': '/bin/bash', 16:11:59 INFO - 'SHLVL': '1', 16:11:59 INFO - 'TERM': 'linux', 16:11:59 INFO - 'TMOUT': '86400', 16:11:59 INFO - 'USER': 'cltbld', 16:11:59 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 16:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:59 INFO - '_': '/tools/buildbot/bin/python'} 16:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:12:00 INFO - Running setup.py (path:/tmp/pip-eAsUVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:12:00 INFO - Running setup.py (path:/tmp/pip-t8aevb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:12:00 INFO - Running setup.py (path:/tmp/pip-4RRMeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:12:00 INFO - Running setup.py (path:/tmp/pip-q9VyDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:12:00 INFO - Running setup.py (path:/tmp/pip-Q0Fteo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:12:00 INFO - Running setup.py (path:/tmp/pip-m4BUES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:12:00 INFO - Running setup.py (path:/tmp/pip-eXYrnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:12:00 INFO - Running setup.py (path:/tmp/pip-6vu6eB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:12:00 INFO - Running setup.py (path:/tmp/pip-fNpWdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:12:00 INFO - Running setup.py (path:/tmp/pip-ix3QVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:12:00 INFO - Running setup.py (path:/tmp/pip-qy0kJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:12:00 INFO - Running setup.py (path:/tmp/pip-GwKD2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:12:00 INFO - Running setup.py (path:/tmp/pip-fcOnW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:12:00 INFO - Running setup.py (path:/tmp/pip-BBIona-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:01 INFO - Running setup.py (path:/tmp/pip-C0c7Lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:12:01 INFO - Running setup.py (path:/tmp/pip-epfFp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:12:01 INFO - Running setup.py (path:/tmp/pip-B5ahDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:12:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:12:01 INFO - Running setup.py install for manifestparser 16:12:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:12:01 INFO - Running setup.py install for mozcrash 16:12:01 INFO - Running setup.py install for mozdebug 16:12:01 INFO - Running setup.py install for mozdevice 16:12:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:12:01 INFO - Running setup.py install for mozfile 16:12:01 INFO - Running setup.py install for mozhttpd 16:12:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:12:01 INFO - Running setup.py install for mozinfo 16:12:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:12:01 INFO - Running setup.py install for mozInstall 16:12:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:12:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:12:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:12:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:12:01 INFO - Running setup.py install for mozleak 16:12:02 INFO - Running setup.py install for mozlog 16:12:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:12:02 INFO - Running setup.py install for moznetwork 16:12:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:12:02 INFO - Running setup.py install for mozprocess 16:12:02 INFO - Running setup.py install for mozprofile 16:12:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:12:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:12:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:12:02 INFO - Running setup.py install for mozrunner 16:12:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:12:02 INFO - Running setup.py install for mozscreenshot 16:12:02 INFO - Running setup.py install for moztest 16:12:02 INFO - Running setup.py install for mozversion 16:12:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:12:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:12:02 INFO - Cleaning up... 16:12:02 INFO - Return code: 0 16:12:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:12:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:02 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/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 0x226c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2434b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d6d60>, '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 0x25d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d7ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:02 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:12:02 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:12:02 INFO - Using env: {'DISPLAY': ':0', 16:12:02 INFO - 'HOME': '/home/cltbld', 16:12:02 INFO - 'LANG': 'en_US.UTF-8', 16:12:02 INFO - 'LANGUAGE': 'en_US:en', 16:12:02 INFO - 'LOGNAME': 'cltbld', 16:12:02 INFO - 'MAIL': '/var/mail/cltbld', 16:12:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:02 INFO - 'MOZ_NO_REMOTE': '1', 16:12:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:02 INFO - 'NO_EM_RESTART': '1', 16:12:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:02 INFO - 'PWD': '/builds/slave/test', 16:12:02 INFO - 'SHELL': '/bin/bash', 16:12:02 INFO - 'SHLVL': '1', 16:12:02 INFO - 'TERM': 'linux', 16:12:02 INFO - 'TMOUT': '86400', 16:12:02 INFO - 'USER': 'cltbld', 16:12:02 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 16:12:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:02 INFO - '_': '/tools/buildbot/bin/python'} 16:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:12:03 INFO - Running setup.py (path:/tmp/pip-tE9KN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:12:03 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)) 16:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:12:03 INFO - Running setup.py (path:/tmp/pip-HqCAts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:12:03 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)) 16:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:12:03 INFO - Running setup.py (path:/tmp/pip-Z_K5v6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:12:03 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)) 16:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:12:03 INFO - Running setup.py (path:/tmp/pip-DGwBIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 16:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:12:03 INFO - Running setup.py (path:/tmp/pip-UbVBtl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:12:03 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)) 16:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:12:03 INFO - Running setup.py (path:/tmp/pip-MwSY3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:12:03 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)) 16:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:12:03 INFO - Running setup.py (path:/tmp/pip-SvkZD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 16:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:12:03 INFO - Running setup.py (path:/tmp/pip-hYEmnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 16:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:12:03 INFO - Running setup.py (path:/tmp/pip-CyOxFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:12:03 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)) 16:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:12:03 INFO - Running setup.py (path:/tmp/pip-6af3Ed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 16:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:12:03 INFO - Running setup.py (path:/tmp/pip-VszW4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:12:03 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)) 16:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:12:03 INFO - Running setup.py (path:/tmp/pip-hoaZ6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:12:04 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)) 16:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:12:04 INFO - Running setup.py (path:/tmp/pip-YjRtU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:12:04 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)) 16:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:12:04 INFO - Running setup.py (path:/tmp/pip-HOIJjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:12:04 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)) 16:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:04 INFO - Running setup.py (path:/tmp/pip-8AJ0EI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:04 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)) 16:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:12:04 INFO - Running setup.py (path:/tmp/pip-3yioJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:12:04 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)) 16:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:12:04 INFO - Running setup.py (path:/tmp/pip-vPHWoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:12:04 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)) 16:12:04 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)) 16:12:04 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)) 16:12:04 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)) 16:12:04 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:12:04 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:12:04 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:12:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:05 INFO - Downloading blessings-1.6.tar.gz 16:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:12:05 INFO - Installing collected packages: blessings 16:12:05 INFO - Running setup.py install for blessings 16:12:05 INFO - Successfully installed blessings 16:12:05 INFO - Cleaning up... 16:12:05 INFO - Return code: 0 16:12:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:12:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:12:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:12:05 INFO - Reading from file tmpfile_stdout 16:12:05 INFO - Current package versions: 16:12:05 INFO - argparse == 1.2.1 16:12:05 INFO - blessings == 1.6 16:12:05 INFO - blobuploader == 1.2.4 16:12:05 INFO - docopt == 0.6.1 16:12:05 INFO - functools32 == 3.2.3-2 16:12:05 INFO - jsonschema == 2.5.1 16:12:05 INFO - manifestparser == 1.1 16:12:05 INFO - mozInstall == 1.13 16:12:05 INFO - mozcrash == 1.0 16:12:05 INFO - mozdebug == 0.1 16:12:05 INFO - mozdevice == 0.51 16:12:05 INFO - mozfile == 1.2 16:12:05 INFO - mozhttpd == 0.7 16:12:05 INFO - mozinfo == 0.10 16:12:05 INFO - mozleak == 0.1 16:12:05 INFO - mozlog == 3.5 16:12:05 INFO - moznetwork == 0.27 16:12:05 INFO - mozprocess == 0.25 16:12:05 INFO - mozprofile == 0.28 16:12:05 INFO - mozrunner == 6.13 16:12:05 INFO - mozscreenshot == 0.1 16:12:05 INFO - mozsystemmonitor == 0.3 16:12:05 INFO - moztest == 0.8 16:12:05 INFO - mozversion == 1.4 16:12:05 INFO - psutil == 3.1.1 16:12:05 INFO - requests == 1.2.3 16:12:05 INFO - wsgiref == 0.1.2 16:12:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:12:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:05 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/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 0x226c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2434b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d6d60>, '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 0x25d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d7ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:05 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:12:05 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:12:05 INFO - Using env: {'DISPLAY': ':0', 16:12:05 INFO - 'HOME': '/home/cltbld', 16:12:05 INFO - 'LANG': 'en_US.UTF-8', 16:12:05 INFO - 'LANGUAGE': 'en_US:en', 16:12:05 INFO - 'LOGNAME': 'cltbld', 16:12:05 INFO - 'MAIL': '/var/mail/cltbld', 16:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:05 INFO - 'MOZ_NO_REMOTE': '1', 16:12:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:05 INFO - 'NO_EM_RESTART': '1', 16:12:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:05 INFO - 'PWD': '/builds/slave/test', 16:12:05 INFO - 'SHELL': '/bin/bash', 16:12:05 INFO - 'SHLVL': '1', 16:12:05 INFO - 'TERM': 'linux', 16:12:05 INFO - 'TMOUT': '86400', 16:12:05 INFO - 'USER': 'cltbld', 16:12:05 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 16:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:05 INFO - '_': '/tools/buildbot/bin/python'} 16:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:06 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)) 16:12:06 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)) 16:12:06 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)) 16:12:06 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)) 16:12:06 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)) 16:12:06 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)) 16:12:06 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)) 16:12:06 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)) 16:12:06 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)) 16:12:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:12: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)) 16:12: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)) 16:12:07 INFO - Installing collected packages: simplejson 16:12:07 INFO - Running setup.py install for simplejson 16:12:07 INFO - building 'simplejson._speedups' extension 16:12:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 16:12:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 16:12:08 INFO - Successfully installed simplejson 16:12:08 INFO - Cleaning up... 16:12:08 INFO - Return code: 0 16:12:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:08 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/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 0x226c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2434b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d6d60>, '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 0x25d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d7ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:12:08 INFO - Using env: {'DISPLAY': ':0', 16:12:08 INFO - 'HOME': '/home/cltbld', 16:12:08 INFO - 'LANG': 'en_US.UTF-8', 16:12:08 INFO - 'LANGUAGE': 'en_US:en', 16:12:08 INFO - 'LOGNAME': 'cltbld', 16:12:08 INFO - 'MAIL': '/var/mail/cltbld', 16:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:08 INFO - 'MOZ_NO_REMOTE': '1', 16:12:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:08 INFO - 'NO_EM_RESTART': '1', 16:12:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:08 INFO - 'PWD': '/builds/slave/test', 16:12:08 INFO - 'SHELL': '/bin/bash', 16:12:08 INFO - 'SHLVL': '1', 16:12:08 INFO - 'TERM': 'linux', 16:12:08 INFO - 'TMOUT': '86400', 16:12:08 INFO - 'USER': 'cltbld', 16:12:08 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 16:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:08 INFO - '_': '/tools/buildbot/bin/python'} 16:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:12:08 INFO - Cleaning up... 16:12:08 INFO - Return code: 0 16:12:08 INFO - Running post-action listener: _resource_record_post_action 16:12:08 INFO - Running post-action listener: _start_resource_monitoring 16:12:08 INFO - Starting resource monitoring. 16:12:08 INFO - [mozharness: 2017-09-24 23:12:08.684979Z] Finished create-virtualenv step (success) 16:12:08 INFO - [mozharness: 2017-09-24 23:12:08.685551Z] Running install step. 16:12:08 INFO - Running pre-action listener: _resource_record_pre_action 16:12:08 INFO - Running main action method: install 16:12:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:12:08 INFO - Reading from file tmpfile_stdout 16:12:08 INFO - Detecting whether we're running mozinstall >=1.0... 16:12:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:12:09 INFO - Reading from file tmpfile_stdout 16:12:09 INFO - Output received: 16:12:09 INFO - Usage: mozinstall [options] installer 16:12:09 INFO - Options: 16:12:09 INFO - -h, --help show this help message and exit 16:12:09 INFO - -d DEST, --destination=DEST 16:12:09 INFO - Directory to install application into. [default: 16:12:09 INFO - "/builds/slave/test"] 16:12:09 INFO - --app=APP Application being installed. [default: firefox] 16:12:09 INFO - mkdir: /builds/slave/test/build/application 16:12:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:12:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:12:26 INFO - Reading from file tmpfile_stdout 16:12:26 INFO - Output received: 16:12:26 INFO - /builds/slave/test/build/application/firefox/firefox 16:12:26 INFO - Running post-action listener: _resource_record_post_action 16:12:26 INFO - [mozharness: 2017-09-24 23:12:26.868645Z] Finished install step (success) 16:12:26 INFO - [mozharness: 2017-09-24 23:12:26.868897Z] Running setup-mitmproxy step. 16:12:26 INFO - Running pre-action listener: _resource_record_pre_action 16:12:26 INFO - Running main action method: setup_mitmproxy 16:12:26 INFO - Skipping: mitmproxy is not required 16:12:26 INFO - Running post-action listener: _resource_record_post_action 16:12:26 INFO - [mozharness: 2017-09-24 23:12:26.870020Z] Finished setup-mitmproxy step (success) 16:12:26 INFO - [mozharness: 2017-09-24 23:12:26.870240Z] Running run-tests step. 16:12:26 INFO - Running pre-action listener: _resource_record_pre_action 16:12:26 INFO - Running pre-action listener: _set_gcov_prefix 16:12:26 INFO - Running main action method: run_tests 16:12:26 WARNING - Try message not found. 16:12:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:12:26 INFO - Python 2.7.3 16:12:26 INFO - Return code: 0 16:12:26 INFO - grabbing minidump binary from tooltool 16:12:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:12:26 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/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 0x25d6d60>, '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 0x25d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25d7ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:12:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:12:26 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:12:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:12:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:12:27 INFO - Return code: 0 16:12:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:12:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:12:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:12:27 INFO - ENV: RUST_BACKTRACE is now full 16:12:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:12:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:12:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 16:12:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 16:12:27 INFO - Using env: {'DISPLAY': ':0', 16:12:27 INFO - 'HOME': '/home/cltbld', 16:12:27 INFO - 'LANG': 'en_US.UTF-8', 16:12:27 INFO - 'LANGUAGE': 'en_US:en', 16:12:27 INFO - 'LOGNAME': 'cltbld', 16:12:27 INFO - 'MAIL': '/var/mail/cltbld', 16:12:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:12:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:27 INFO - 'MOZ_NO_REMOTE': '1', 16:12:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:12:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:27 INFO - 'NO_EM_RESTART': '1', 16:12:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:27 INFO - 'PWD': '/builds/slave/test', 16:12:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:12:27 INFO - 'RUST_BACKTRACE': 'full', 16:12:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:12:27 INFO - 'SHELL': '/bin/bash', 16:12:27 INFO - 'SHLVL': '1', 16:12:27 INFO - 'TERM': 'linux', 16:12:27 INFO - 'TMOUT': '86400', 16:12:27 INFO - 'USER': 'cltbld', 16:12:27 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 16:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:27 INFO - '_': '/tools/buildbot/bin/python'} 16:12:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 16:12:27 INFO - mozversion application_buildid: 20170924215115 16:12:27 INFO - mozversion application_changeset: d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 16:12:27 INFO - mozversion application_display_name: Nightly 16:12:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:12:27 INFO - mozversion application_name: Firefox 16:12:27 INFO - mozversion application_remotingname: firefox 16:12:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:12:27 INFO - mozversion application_vendor: Mozilla 16:12:27 INFO - mozversion application_version: 58.0a1 16:12:27 INFO - mozversion platform_buildid: 20170924215115 16:12:27 INFO - mozversion platform_changeset: d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 16:12:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:12:27 INFO - mozversion platform_version: 58.0a1 16:12:27 INFO - using testdate: 1506294747 16:12:27 INFO - actual date: 1506294747 16:12:27 INFO - starting webserver on 'localhost:37918' 16:12:27 INFO - SUITE-START | Running 2 tests 16:12:27 INFO - TEST-START | dromaeo_css 16:12:27 INFO - Initialising browser for dromaeo_css test... 16:12:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37918/getInfo.html -profile /tmp/tmpMOxZXm/profile 16:12:27 INFO - TEST-INFO | started process 11168 (/builds/slave/test/build/application/firefox/firefox http://localhost:37918/getInfo.html) 16:12:33 INFO - TEST-INFO | 11168: exit 0 16:12:33 INFO - Browser initialized. 16:12:33 INFO - Running cycle 1/1 for dromaeo_css test... 16:12:33 INFO - Using env: {'DISPLAY': ':0', 16:12:33 INFO - 'HOME': '/home/cltbld', 16:12:33 INFO - 'JSGC_DISABLE_POISONING': '1', 16:12:33 INFO - 'LANG': 'en_US.UTF-8', 16:12:33 INFO - 'LANGUAGE': 'en_US:en', 16:12:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:12:33 INFO - 'LOGNAME': 'cltbld', 16:12:33 INFO - 'MAIL': '/var/mail/cltbld', 16:12:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:12:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:12:33 INFO - 'MOZ_CRASHREPORTER': '1', 16:12:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:12:33 INFO - 'MOZ_NO_REMOTE': '1', 16:12:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:12:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:33 INFO - 'NO_EM_RESTART': '1', 16:12:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:33 INFO - 'PWD': '/builds/slave/test', 16:12:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:12:33 INFO - 'RUST_BACKTRACE': 'full', 16:12:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:12:33 INFO - 'SHELL': '/bin/bash', 16:12:33 INFO - 'SHLVL': '1', 16:12:33 INFO - 'TERM': 'linux', 16:12:33 INFO - 'TMOUT': '86400', 16:12:33 INFO - 'USER': 'cltbld', 16:12:33 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 16:12:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:33 INFO - '_': '/tools/buildbot/bin/python'} 16:12:33 INFO - TEST-INFO | started process 11406 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMOxZXm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:12:34 INFO - PID 11406 | 16:12:34 INFO - PID 11406 | (/builds/slave/test/build/application/firefox/firefox:11457): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:12:34 INFO - PID 11406 | 16:12:44 INFO - PID 11406 | RSS: Main: 140877824 16:12:44 INFO - PID 11406 | 16:15:42 INFO - PID 11406 | Cycle 1(1): loaded http://localhost:37918/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37918/tests/dromaeo/cssquery-ext.html) 16:15:43 INFO - PID 11406 | RSS: Main: 145772544 16:15:43 INFO - PID 11406 | 16:18:45 INFO - PID 11406 | Cycle 1(1): loaded http://localhost:37918/tests/dromaeo/cssquery-ext.html (next: http://localhost:37918/tests/dromaeo/cssquery-jquery.html) 16:18:45 INFO - PID 11406 | RSS: Main: 145637376 16:18:45 INFO - PID 11406 | 16:21:47 INFO - PID 11406 | Cycle 1(1): loaded http://localhost:37918/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37918/tests/dromaeo/cssquery-mootools.html) 16:21:47 INFO - PID 11406 | RSS: Main: 148013056 16:21:47 INFO - PID 11406 | 16:24:49 INFO - PID 11406 | Cycle 1(1): loaded http://localhost:37918/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37918/tests/dromaeo/cssquery-prototype.html) 16:24:50 INFO - PID 11406 | RSS: Main: 146939904 16:24:50 INFO - PID 11406 | 16:27:53 INFO - PID 11406 | Cycle 1(1): loaded http://localhost:37918/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37918/tests/dromaeo/cssquery-yui.html) 16:27:53 INFO - PID 11406 | RSS: Main: 147746816 16:27:53 INFO - PID 11406 | 16:30:55 INFO - PID 11406 | Cycle 1(1): loaded http://localhost:37918/tests/dromaeo/cssquery-yui.html (next: http://localhost:37918/tests/dromaeo/cssquery-dojo.html) 16:30:55 INFO - PID 11406 | RSS: Main: 150327296 16:30:55 INFO - PID 11406 | 16:30:55 INFO - PID 11406 | __start_tp_report 16:30:55 INFO - PID 11406 | _x_x_mozilla_page_load 16:30:55 INFO - PID 11406 | _x_x_mozilla_page_load_details 16:30:55 INFO - PID 11406 | |i|pagename|runs| 16:30:55 INFO - PID 11406 | |0;dojo.html;3812.904677383066;3819.1979684266303;3826.234753049389;3838.7888666123363;3879.3211188042105;6232.940400131977;6283.874322513547;6357.30069692334;6426.48588112951;6445.742170313182;6368.426841584256;6411.839704007402;6418.71115799789;6421.197350331208;6432;5635.380108188096;5711.942880571193;5737.512311453532;5744;5804.622699524539;5774.220480235156;5798.072308430652;5827.242458480413;5830.970845145789;5838.854028649296;923.0129250122781;5698.059820129673;5762.53899688026;5856.853578660525;5864.530837533009;5700.543956483471;5705.315362156568;5717.171010203498;5828.795992140254;5834.154047663065;5679.204911312419;5693.174489699012;5759.2512973313205;5760.567957403212;5764.827055188351;6307.842303942392;6323.083152942843;6329.873402531924;6351.745930162834;6420.839479013015;6899.586024838526;6903.309669033107;6998.195207551146;7002.47481438889;7023.157221133446;6836.726530938806;6895.655217239118;6941.583504989717;7033.4724895633035;7043.401310888581;6321.209848768904;6321.841953951142;6327.082573026885;6328.683565821737;6329.082283068929;6905.620190889549;6971.721131154697;6972.1981972072845;6981.685824137926;7000.754973576002;6701.597904125768;6763.323667633295;6783.084283621746;6790.7623233186605;6810.080639113756;6893.896591551131;6912.965435172792;6989.965050174716;7031.8945215821805;7061.505694601328;6758.155230596176;6841.692123854438;6850.6574671266235;6868.038474613558;6943.79168624942;6743.763968261087;6863.759768408182;6890.2420733719255;6910.619915904573;6923.930760692429;4981.576565991859;4994.225894986283;4995.25071239317;5006.599472042228;5058.747062646853;4516;4547.8635640930825;4585.1746685596245;4605.907881842344;4618.815247390134;6264.561480696308;6291.433770960635;6347.809565713036;6348.746050158034;6355.983042713144;6862.622555759382;6887.690053947585;6952.652367381611;6966.12923384577;6970.024196612479;6223.844403889849;6384.170057892537;6412.807615771534;6420.7431702731365;6427.967860160669;5584.692841893653;5593.552515798727;5648.350439699405;5666.065099258576;5669.6314739541795;6999.195092564319;7093.0424392707355;7159.821004474929;7197.388222001084;7199.06412166415;6950.165980082423;6958.617276049866;6991.265917078634;7010.684519196547;7017.824554385977;7121.608311542915;7125.8574828502105;7133.857322853618;7138.107736532934;7245.347918687344;6625.933740662532;6730.966345168243;6738.022986666808;6839.384455398839;6930.06444130036;6867.725290988305;6919.065926100114;6920.896186557105;6931.44548436134;6964.442844572523;6692.899606506004;6754.662266886734;6765.1205343305055;6768.357006084414;6778.728850846108;6509.121268628674;6614.636195009129;6619.27188009309;6639.302873198245;6739.05653208561;6587.736490540325;6661.566998145105;6680.632565208765;6689.130413046468;6693.564918280296;6658.400743933069;6658.966705166443;6705.430038446591;6757.256701762901;6769.052332673528;6474.482041436768;6518.120053792867;6566.4418524425955;6590.242122155822;6598.472122230306 16:30:55 INFO - PID 11406 | |1;ext.html;951.3388195204336;954.8376775948088;990.8563258327541;1013.8023085498329;1016.2124353625939;45761;45938.31092533615;46165.7691711541;46291.07417851641;46533.06933861325;43336;43421;43429.7828510857;43508;43584.78207608966;7713.652885620132;7842.254985776342;7868.567228802413;7870.685172593089;7890.960545197266;7592.430567707416;7664.463487555873;7673.769786906401;7702.383809295271;7717.8070548236465;7751;8631.180037896389;8649.178328058824;8651.351148663844;8654.437461564978;8474.152584741538;8506.106858779833;8528.74413767588;8559.358048146352;8569.228769410784;8228.465149765307;8390.035145958233;8461.492310461392;8477.109903460172;8478.533680647563;41519.7924010381;42126.57873421257;42246.366304505456;42253.36619950703;42268;3635.5455568053994;3670.0274427276795;3675.0996005978704;3679.1170119171475;3682.742208045438;34478.82760586181;34604.307913841585;34613.307733845184;34654.653453465646;34654.826725866464;5429.375621803464;5449.264349312872;5472.945270547283;5491.945080549183;5504.0643090674685;207282;207294;207571.9999999985;207593;209225;52638.47361526412;52829.47170528323;55535.16697249545;56640.716796415756;56841;58710;59190.70404648035;59201.703991479764;59464.405355945884;59489.40510594839;15130.697386052218;15498.922505387402;17357.65284694299;17404.129793510274;17422.128893555273;11998.940005299917;12039.819402708967;12083.093767967262;12116.878831211576;12143.210691305037;15652.4521641742;15744.291506882219;15856.52430427089;15950.920245398698;15954.601134971486;6088.438263615746;6100.847478812976;6141.84645383869;6178.567500274938;6212.81361559154;1056.4400867540216;1062.1768129699544;1068.695421804782;1079.9784004320027;1092.8032954068344;25204.84120120196;25864.48271034569;25886.352841178745;26010.6098408524;26072.34819129499;1175.0834349207632;1185.6265276437894;1186.4423720851187;1186.786378451887;1187.352892673495;13452.46190152383;13496.595102146952;13677.452901883813;13768.65578360549;13881.236531990635;278548;280787;280907;280988;281057;58389.70805146032;58620.41379586235;59528.404715953155;63038.68480657567;63136.36863631305;1472.4699108320997;1484.1169504145016;1493.1265209852359;1502.4891536877296;1509.7584386498108;31930.36139277248;32027.199320017186;32028.19929501781;32320.838395808812;32408.35183296368;40845.591544085364;42217;42227.3665895018;42519;42585.36121958235;182941;184506;184987;185764;186090.06954965138;38520;38546.421803672405;38601;38619.613803862725;38891.2221755569;47143.52856471392;47313.763431182626;47507;47597.76201118972;47813.28280075732;33309.83345083259;34830;34867.65132348644;34987.82506087453;35246.11884702903;34803.477947830295;35055.649443506256;35156;35191.47212791861;35368.646313536534;41319;41583.1683366337;41797.37303940383;41802.37296440617;41953.16093678171;34100;34366;34465.96602101941;34753.65246347504;34856.302873942885;25720.228393148234;25844.870775646003;25999.61000584955;26040.34899127537;26168.60747088757 16:30:55 INFO - PID 11406 | |2;jquery.html;598.1587459567056;607.6141650052216;614.5421660862658;625.8935980883249;635.1045026512617;23380.298591042276;23403.76596234037;24351.14770983016;24368.26895193147;24421.267361979124;25207.117750878722;25900.611490827654;26002.219933401982;26064.218073457778;26092.34769130768;6542;6749.93250067498;6759.459243260552;6764.966175169117;6778.356056174655;6459.806205813809;6539.280679125292;6661.933380666204;6689.264180940093;6721.966390168042;6676.499262555303;6702.731890724365;6702.966485167567;6722;6723.798286051427;6452;6627.436667883236;6651.80044598658;6670.366315200048;6674.566153200027;6209.099680546341;6216.191895053659;6232.065190221458;6235.158253635746;6243.906341404882;29011;29151.708482915325;29309.26726831825;29349.853250733824;29509.852450737824;2218.4786575154826;2259.4464356232666;2261.2424837679396;2261.5929132756096;2262.513559584681;19551.511212219666;20624.484387890272;20706.79293207064;20723.378298651063;20804.791952080435;2475.8885850136785;2493.6010238361773;2508.4481414089055;2536.644869718241;2545.9363515912064;129930;135858.32070839682;149494;151155;155019;15434.84565154334;15926.929499165035;16143.757843632355;16604.335826566803;16815.579610509834;26065;26131.346716332242;26138.6079208812;26144.346391339986;26227.73772262291;10496.317739346918;10902.727431814268;11023.17326200538;11112.944435277772;11120.499577519031;9848.409095454295;9948.303618746619;9974.551145198484;10088.798224035478;10105.797884042278;9626.671054665485;9812.509374531388;9865.309428339948;9917.107460328605;10073.395596264101;2835.943281134366;2905.680375158731;2921.4887394706266;2988.357503136836;3049.557814116941;1042.4943902207424;1052.4685034057904;1052.6736711619421;1059.5073290919759;1064.3932958213898;20551.691724624143;21216.575668486545;21221.89389053045;21425;21489.032993515328;1098.8406681031206;1100.983485247722;1113.3932007056173;1121.6522877907876;1124.533318672758;11446.885531144748;11622.476988535536;11659;11661.88338116625;11691.473883675255;602476;603060;610813;611121;616072;125783.371083144;127103.36448317699;128380;130655;131806;1116.2855772305732;1204.265398107154;1214.684182112657;1228.336698182989;1258.704204511939;21855.235066773224;22078.88960555187;22325.888370558045;22388.664170037137;22399.32802015942;62592.68703656452;63865;65013.674931626934;65392.673036634515;66417.66791166013;81292.59353703193;81634.18365816266;82109;83034.58482707548;84244.73632895389;15303.46437874669;15556.455524056604;15721.292541835874;15727.685446290781;15949.760753588473;24186.274411767674;24269.878650606635;24318.27045188647;24438;24836.627450588618;19837;19847.206111755368;19863;19979.300724474575;20039.398818035483;13794.241316727675;14469;16321.918390407973;17864.642707145525;17932.820671793113;18581.535461613566;19228.423147305602;19438;19693.901530492254;20151.294704685264;15196.772048419503;15399.692006160038;15488.767668485207;15496.457623983106;15581.610459738597;19209.231630734615;19277.614447711247;19446.902765486648;19470.221191152195;19478.415647530597 16:30:55 INFO - PID 11406 | |3;mootools.html;1442.949496767613;1497.970040599188;1500.2798656644804;1510.403390660689;1512.5311153542402;16900.492985210432;17387.478375648752;17463.91268043658;17479.213435395406;17548.82451175488;17012.82987170128;17070.48788536346;17122.05828679422;17127.486175414757;17145.31418743252;2796.384795345031;2805.523061079616;2867.612872262239;2870.382867683448;2872.8707208175683;2757.6277202577694;2792.5531914893622;2795.7483826455614;2800;2830.80184387093;2872.626558547386;2910.519764238898;2911.0539074800668;2911.7233862783;2912.504874171396;2742.191053639181;2783.526800443909;2788.9721102788917;2794.259521226866;2809.381935974093;2727.6726792784993;2729.890804367823;2737.986310068437;2740.739629735172;2742.026580563911;16151.273192706356;16562.917185414117;16662.58343541409;16753.329866805314;16755.91622041894;8139.837203255961;8451.619677114544;8456.36577256702;8461.365397595144;8464.1112683168;5602.831915042514;5783.277090363705;5822.184894114827;5901.8819623607715;6067.666278354689;2087.4363921741196;2140.646793279107;2145.249162793026;2196.8681879087144;2226.365485836529;30495;30763.538546921813;30770;30801;30854.999999999774;7088;7094.467914906402;7112.573245605176;7178.748743793943;7283.126024876943;18879.90560047191;18996.905015474833;19113.52216194606;19142.138603762865;19177.712334314998;8390.28682561989;8399.748007559783;8410.579471026424;8419;8427.831443371098;7292.41660667156;7710.344620707303;7716.961415193;7830.177831327628;7838.921610783933;5716.914246286309;5720.2849643794525;5775.422457754191;5836.620619659709;5836.941630583725;1607.5257798949337;1661.5264649574813;1661.9584510387094;1672.4230140601474;1691.8984860908386;1020.2807021050141;1023.7389465686351;1033.5555711044324;1038.600138946511;1054.6835949215206;9441.386309889835;9451.243900488082;9479.194268487256;9494.905050949541;9615.711528654152;1054.288355360129;1076.4025965589017;1091.4324551233315;1093.4587379247323;1096.1011970184531;5508.366537848118;5537;6093.847653808691;6281.685915704196;6307.590006649553;24633;25041.499170016497;25172.24483265505;25379.98480060777;25426;23686.407839804142;24640;24662.26013219606;24832.37919052038;24903.62644560333;12528.81206781899;12538.623841284776;12792.872071279355;12828.615141545955;12829.422675979604;9782.461964592016;9893.159081478156;9894;9961.750956226153;10002.799944001225;20230;20563.897180514003;21310.467238319165;21405.35783926485;21569.568608627425;21835.344939651837;21932.780672193076;22133.889330553244;22200.33398997968;22257.554848903255;12250.571230007263;12465.5013799447;12468.438920248793;12477.188982716094;12504.874951250371;15520.068795872285;15595.376184952474;15626.921865391056;15778.368865245262;15784.684306314039;12412.875871241533;12542.49830006826;12916.741665166832;12927.806082908952;12951.222926624432;12175.878241217475;12247.938760306142;12355.93822030884;12497.875021250034;12592.803683650038;10996.395198263852;11233.55065797359;11265.493052812806;11279.92840680134;11293.153013523854;8462.788430289293;8629.13708629132;8708.56457177126;8724.127587241224;8748.168923952215;8552.914470855212;8576.742697719079;8646.697365592425;8706.521141337287;8741.650333986569 16:30:55 INFO - PID 11406 | |4;prototype.html;558.9329280486343;561.2843624378918;563.0474072014456;585.6164212440852;586.8386193796705;6133.938660613392;6222.191115155029;6264.279607845094;6326.493880489561;6330.33531479195;6225.661482781201;6244.781432649858;6288.025356069817;6296.307406185316;6341.809745707625;5218.504242097014;5220.216967454884;5250.65870718402;5269.604779641523;5273.288106105684;4918.508149185071;5016.573591244749;5192.974035129819;5237.764300606482;5248.501392367738;4963.106640804654;4994.625403094764;5017.448080711119;5031.723255220962;5032.270320803481;4989.950100498985;4996.475370086125;4997.125503036954;5020.196768517038;5048.6465947383695;4554.954450455486;4570.862874113782;4579.63362930968;4591.6097131743845;4606.239970404879;6147.692615369258;6206.906896396557;6212;6233.5013198944425;6293;5931.466168044896;5945.851353716149;5972.25346831646;5980.641161530322;5982.102684597302;6540.640264785435;6573.244076931166;6645.435138013275;6683.699233534503;6731.93268067318;4836.202026665596;5090.720010399426;5102.234664800272;5103.591712663015;5146.665466744649;6874.793756187322;6881.311868813129;6984.650767461658;6995.475339349569;7001.124859392576;5216.113260745682;5223.738813059408;5225.451327610622;5277.577793776489;5283.788648454019;5303.867403314871;5311.123664595376;5313.973430132824;5353;5359.705216213068;5879.412058794086;5900.852478687982;5923.7926672566255;5961.105834124916;5974.61165024272;6712.053504483645;6720.8319792005595;6743.224529179109;6747.156605424322;6761.357671021246;1819.0995457248746;1830.213008406398;1832.3128826689992;1847.6951303035119;1856.832885040353;1234.7283597608512;1306.405585458614;1312.9737405251842;1319.327143156997;1336.9398377073055;5971.253593300838;6110.602810817282;6186.721597528122;6196.47329976948;6196.783112591038;6586.868262634721;6674.399304062657;6720.630365329954;6766.458683305324;6842.486813488908;6117.84705382369;6155.599886007485;6158.414950579688;6167.506599472032;6204.689765511706;3586.4799604057266;3590.7791350940806;3595.280943811248;3601.1177261571083;3617.4212126059706;6867.2102708189195;6920.792376228721;6993.510454268254;7047.154341479055;7059.89410158848;6872.209695884937;6878.209005964278;6896.793096207121;6913.306239971139;6941.514094013371;6971.5359774446915;6988.545744526589;7010.544314619636;7081.291870812877;7087.539309944837;6611.867762644816;6630.569013014138;6670.266270710121;6693.866122677617;6737.5407976089355;6576.145101136822;6613.933860661525;6621;6676.165479315086;6738.831529211809;6867.896981545381;7006.2643422439905;7007.124109486314;7111.395531379891;7128.964355178398;5191.506806853343;5195.5843532518065;5216.739163041757;5273.578113750814;5277.788888444419;6485.346236709609;6692.6988285526295;6697.464202863662;6744.785938531111;6783.18601767791;6084.787032453843;6537.444317232898;6611.471082313499;6619.271880093283;6635.4359879410795;6471.902921456276;6517.6638789049075;6550.410463058348;6568.441682457044;6623.536352455475;6323.447118074979;6473.805785826433;6486.643234622141;6513.804585862432;6564.671766411756;6556.508261880283;6641.667916604247;6663.033860090357;6695.765648202289;6714.899276510759;6365.363463653597;6368.64972426521;6486.643234622141;6560.114384558215;6567.277599464151 16:30:55 INFO - PID 11406 | |5;yui.html;942.5193151492737;956.9138777510022;961.8124465729181;965.0542468380982;970.2916870684403;20171.495712607193;20705.06827192776;20729.378118656463;20748.89625551872;20771.376858694224;19649.803501964976;19683.311084112058;19834.206631734713;19866.602667946634;19867.10598023089;988.8071825993934;1002.8896821349664;1005.7686732051633;1014.0822555587206;1014.6702321745425;1062.9893701062967;1063.7393838509577;1065.2223876570108;1070.1118072000222;1070.8982646648594;1071.1216802222182;1102.0852692265441;1102.404701461214;1106.634810512533;1109.8002359575273;1104.309806371018;1105.2042529378834;1106.419129956771;1106.8007758603449;1107.5514416661224;795.7732046366758;813.7640084375583;816.6774124220959;817.9345652347798;821.7740121466585;21231.893840530855;21496.892515537478;21503.892480537495;21538.353849384544;21540.676889846665;23089.0764369425;23267.767322326898;23562.410939726473;23680;23917.641235381485;18071.819281807275;18338.724919126224;18602.162902669414;18719.812801871944;18734.437966861016;3974.86087986922;3999.240144372589;4003.179348233605;4003.4795476588147;4005.819738111792;137300;162029;162534;162572;166702;18508.629827403645;18634.25462981493;18871.245150193838;18922.24311027571;19147.13837877299;33615.49576756351;33676.66323336784;33952.32095358079;34225;34525.13687157841;9894.356866803635;9981.101700846959;10001.049900259464;10011.099001089937;10043.648472303434;4701.506341834127;4729.7398643074275;4795.472498025214;4842.612590992712;4878.585320247747;6169.290531588835;6240.469560087353;6242.188515492957;6244;6273.278572964076;1240.838690970168;1274.2672963046286;1301.1477482249143;1304.4716889659703;1308.117021010814;728.6211170191576;738.2728012907282;741.8219627289411;757.1519897714485;768.5734417398397;12258.448369823202;12729;12756;13766.31168441574;14003.789943150861;734.9081364829397;736.6169591812228;747.8317378589774;753.1038064702985;755.316438623047;5815.88368232633;5834.066549352084;5870.14882841994;5893.557983151195;5942.168096466498;147438.2628086874;148251;149395;150496;152755;132248;132570.33714831556;133396;135317;141412.2929385367;115036;115237.42381288207;116314;117009;118208;11100.5559777608;11263.66209013731;11271.436428178395;11325.660230193107;11538.88461115378;56265.43734562602;57211.71394143169;57584.984525541404;57734.711326443095;58294;70568.6471567639;73621.26378736144;74764.25235747574;75001.62499187469;75290;17785.73321400206;17895.73156402681;17935.820641793416;18068.457946261635;18141.45575632733;19532.707009394588;19982.800171998675;20260.59478810386;20504.897475512527;20619.175232990514;17253.56866078358;17318.913405432893;17360.73958890643;17399.56501087483;17562.560935976704;17371.739423908904;17602.38391656286;17712.55718607045;17761;17781.822181778018;17871.910640447233;18191.363302284357;18218.36235731743;18224.088795560434;18426.815731843046;12087.609924858401;12134.575289864812;12141.210821296587;12256.938715306367;12603.432845522157;12192.207506512048;12209.023278137905;12261.938690306491;12367.93816030914;12571.1200215984 16:30:55 INFO - PID 11406 | __end_tp_report 16:30:55 INFO - PID 11406 | __start_cc_report 16:30:55 INFO - PID 11406 | _x_x_mozilla_cycle_collect,284 16:30:55 INFO - PID 11406 | __end_cc_report 16:30:55 INFO - PID 11406 | __startTimestamp1506295855259__endTimestamp 16:30:55 INFO - PID 11406 | 16:30:55 INFO - PID 11406 | ------- Summary: start ------- 16:30:55 INFO - PID 11406 | Number of tests: 6 16:30:55 INFO - PID 11406 | 16:30:55 INFO - PID 11406 | [#0] dojo.html Cycles:165 Average:6320.12 Median:6619.27 stddev:866.27 (13.1%) stddev-sans-first:846.30 16:30:55 INFO - PID 11406 | Values: 3812.9 3819.2 3826.2 3838.8 3879.3 6232.9 6283.9 6357.3 6426.5 6445.7 6368.4 6411.8 6418.7 6421.2 6432.0 5635.4 5711.9 5737.5 5744.0 5804.6 5774.2 5798.1 5827.2 5831.0 5838.9 923.0 5698.1 5762.5 5856.9 5864.5 5700.5 5705.3 5717.2 5828.8 5834.2 5679.2 5693.2 5759.3 5760.6 5764.8 6307.8 6323.1 6329.9 6351.7 6420.8 6899.6 6903.3 6998.2 7002.5 7023.2 6836.7 6895.7 6941.6 7033.5 7043.4 6321.2 6321.8 6327.1 6328.7 6329.1 6905.6 6971.7 6972.2 6981.7 7000.8 6701.6 6763.3 6783.1 6790.8 6810.1 6893.9 6913.0 6990.0 7031.9 7061.5 6758.2 6841.7 6850.7 6868.0 6943.8 6743.8 6863.8 6890.2 6910.6 6923.9 4981.6 4994.2 4995.3 5006.6 5058.7 4516.0 4547.9 4585.2 4605.9 4618.8 6264.6 6291.4 6347.8 6348.7 6356.0 6862.6 6887.7 6952.7 6966.1 6970.0 6223.8 6384.2 6412.8 6420.7 6428.0 5584.7 5593.6 5648.4 5666.1 5669.6 6999.2 7093.0 7159.8 7197.4 7199.1 6950.2 6958.6 6991.3 7010.7 7017.8 7121.6 7125.9 7133.9 7138.1 7245.3 6625.9 6731.0 6738.0 6839.4 6930.1 6867.7 6919.1 6920.9 6931.4 6964.4 6692.9 6754.7 6765.1 6768.4 6778.7 6509.1 6614.6 6619.3 6639.3 6739.1 6587.7 6661.6 6680.6 6689.1 6693.6 6658.4 6659.0 6705.4 6757.3 6769.1 6474.5 6518.1 6566.4 6590.2 6598.5 16:30:55 INFO - PID 11406 | 16:30:55 INFO - PID 11406 | [#1] ext.html Cycles:180 Average:41420.75 Median:3680.93 stddev:59315.94 (1611.4%) stddev-sans-first:59404.50 16:30:55 INFO - PID 11406 | Values: 951.3 954.8 990.9 1013.8 1016.2 45761.0 45938.3 46165.8 46291.1 46533.1 43336.0 43421.0 43429.8 43508.0 43584.8 7713.7 7842.3 7868.6 7870.7 7891.0 7592.4 7664.5 7673.8 7702.4 7717.8 7751.0 8631.2 8649.2 8651.4 8654.4 8474.2 8506.1 8528.7 8559.4 8569.2 8228.5 8390.0 8461.5 8477.1 8478.5 41519.8 42126.6 42246.4 42253.4 42268.0 3635.5 3670.0 3675.1 3679.1 3682.7 34478.8 34604.3 34613.3 34654.7 34654.8 5429.4 5449.3 5472.9 5491.9 5504.1 207282.0 207294.0 207572.0 207593.0 209225.0 52638.5 52829.5 55535.2 56640.7 56841.0 58710.0 59190.7 59201.7 59464.4 59489.4 15130.7 15498.9 17357.7 17404.1 17422.1 11998.9 12039.8 12083.1 12116.9 12143.2 15652.5 15744.3 15856.5 15950.9 15954.6 6088.4 6100.8 6141.8 6178.6 6212.8 1056.4 1062.2 1068.7 1080.0 1092.8 25204.8 25864.5 25886.4 26010.6 26072.3 1175.1 1185.6 1186.4 1186.8 1187.4 13452.5 13496.6 13677.5 13768.7 13881.2 278548.0 280787.0 280907.0 280988.0 281057.0 58389.7 58620.4 59528.4 63038.7 63136.4 1472.5 1484.1 1493.1 1502.5 1509.8 31930.4 32027.2 32028.2 32320.8 32408.4 40845.6 42217.0 42227.4 42519.0 42585.4 182941.0 184506.0 184987.0 185764.0 186090.1 38520.0 38546.4 38601.0 38619.6 38891.2 47143.5 47313.8 47507.0 47597.8 47813.3 33309.8 34830.0 34867.7 34987.8 35246.1 34803.5 35055.6 35156.0 35191.5 35368.6 41319.0 41583.2 41797.4 41802.4 41953.2 34100.0 34366.0 34466.0 34753.7 34856.3 25720.2 25844.9 25999.6 26040.3 26168.6 16:30:55 INFO - PID 11406 | 16:30:55 INFO - PID 11406 | [#2] jquery.html Cycles:180 Average:39761.68 Median:2261.42 stddev:101621.54 (4493.7%) stddev-sans-first:101864.07 16:30:55 INFO - PID 11406 | Values: 598.2 607.6 614.5 625.9 635.1 23380.3 23403.8 24351.1 24368.3 24421.3 25207.1 25900.6 26002.2 26064.2 26092.3 6542.0 6749.9 6759.5 6765.0 6778.4 6459.8 6539.3 6661.9 6689.3 6722.0 6676.5 6702.7 6703.0 6722.0 6723.8 6452.0 6627.4 6651.8 6670.4 6674.6 6209.1 6216.2 6232.1 6235.2 6243.9 29011.0 29151.7 29309.3 29349.9 29509.9 2218.5 2259.4 2261.2 2261.6 2262.5 19551.5 20624.5 20706.8 20723.4 20804.8 2475.9 2493.6 2508.4 2536.6 2545.9 129930.0 135858.3 149494.0 151155.0 155019.0 15434.8 15926.9 16143.8 16604.3 16815.6 26065.0 26131.3 26138.6 26144.3 26227.7 10496.3 10902.7 11023.2 11112.9 11120.5 9848.4 9948.3 9974.6 10088.8 10105.8 9626.7 9812.5 9865.3 9917.1 10073.4 2835.9 2905.7 2921.5 2988.4 3049.6 1042.5 1052.5 1052.7 1059.5 1064.4 20551.7 21216.6 21221.9 21425.0 21489.0 1098.8 1101.0 1113.4 1121.7 1124.5 11446.9 11622.5 11659.0 11661.9 11691.5 602476.0 603060.0 610813.0 611121.0 616072.0 125783.4 127103.4 128380.0 130655.0 131806.0 1116.3 1204.3 1214.7 1228.3 1258.7 21855.2 22078.9 22325.9 22388.7 22399.3 62592.7 63865.0 65013.7 65392.7 66417.7 81292.6 81634.2 82109.0 83034.6 84244.7 15303.5 15556.5 15721.3 15727.7 15949.8 24186.3 24269.9 24318.3 24438.0 24836.6 19837.0 19847.2 19863.0 19979.3 20039.4 13794.2 14469.0 16321.9 17864.6 17932.8 18581.5 19228.4 19438.0 19693.9 20151.3 15196.8 15399.7 15488.8 15496.5 15581.6 19209.2 19277.6 19446.9 19470.2 19478.4 16:30:55 INFO - PID 11406 | 16:30:55 INFO - PID 11406 | [#3] mootools.html Cycles:180 Average:10503.07 Median:24868.00 stddev:7686.52 (30.9%) stddev-sans-first:7677.94 16:30:55 INFO - PID 11406 | Values: 1442.9 1498.0 1500.3 1510.4 1512.5 16900.5 17387.5 17463.9 17479.2 17548.8 17012.8 17070.5 17122.1 17127.5 17145.3 2796.4 2805.5 2867.6 2870.4 2872.9 2757.6 2792.6 2795.7 2800.0 2830.8 2872.6 2910.5 2911.1 2911.7 2912.5 2742.2 2783.5 2789.0 2794.3 2809.4 2727.7 2729.9 2738.0 2740.7 2742.0 16151.3 16562.9 16662.6 16753.3 16755.9 8139.8 8451.6 8456.4 8461.4 8464.1 5602.8 5783.3 5822.2 5901.9 6067.7 2087.4 2140.6 2145.2 2196.9 2226.4 30495.0 30763.5 30770.0 30801.0 30855.0 7088.0 7094.5 7112.6 7178.7 7283.1 18879.9 18996.9 19113.5 19142.1 19177.7 8390.3 8399.7 8410.6 8419.0 8427.8 7292.4 7710.3 7717.0 7830.2 7838.9 5716.9 5720.3 5775.4 5836.6 5836.9 1607.5 1661.5 1662.0 1672.4 1691.9 1020.3 1023.7 1033.6 1038.6 1054.7 9441.4 9451.2 9479.2 9494.9 9615.7 1054.3 1076.4 1091.4 1093.5 1096.1 5508.4 5537.0 6093.8 6281.7 6307.6 24633.0 25041.5 25172.2 25380.0 25426.0 23686.4 24640.0 24662.3 24832.4 24903.6 12528.8 12538.6 12792.9 12828.6 12829.4 9782.5 9893.2 9894.0 9961.8 10002.8 20230.0 20563.9 21310.5 21405.4 21569.6 21835.3 21932.8 22133.9 22200.3 22257.6 12250.6 12465.5 12468.4 12477.2 12504.9 15520.1 15595.4 15626.9 15778.4 15784.7 12412.9 12542.5 12916.7 12927.8 12951.2 12175.9 12247.9 12355.9 12497.9 12592.8 10996.4 11233.6 11265.5 11279.9 11293.2 8462.8 8629.1 8708.6 8724.1 8748.2 8552.9 8576.7 8646.7 8706.5 8741.7 16:30:55 INFO - PID 11406 | 16:30:55 INFO - PID 11406 | [#4] prototype.html Cycles:180 Average:5657.71 Median:6223.93 stddev:1564.51 (25.1%) stddev-sans-first:1521.37 16:30:55 INFO - PID 11406 | Values: 558.9 561.3 563.0 585.6 586.8 6133.9 6222.2 6264.3 6326.5 6330.3 6225.7 6244.8 6288.0 6296.3 6341.8 5218.5 5220.2 5250.7 5269.6 5273.3 4918.5 5016.6 5193.0 5237.8 5248.5 4963.1 4994.6 5017.4 5031.7 5032.3 4990.0 4996.5 4997.1 5020.2 5048.6 4555.0 4570.9 4579.6 4591.6 4606.2 6147.7 6206.9 6212.0 6233.5 6293.0 5931.5 5945.9 5972.3 5980.6 5982.1 6540.6 6573.2 6645.4 6683.7 6731.9 4836.2 5090.7 5102.2 5103.6 5146.7 6874.8 6881.3 6984.7 6995.5 7001.1 5216.1 5223.7 5225.5 5277.6 5283.8 5303.9 5311.1 5314.0 5353.0 5359.7 5879.4 5900.9 5923.8 5961.1 5974.6 6712.1 6720.8 6743.2 6747.2 6761.4 1819.1 1830.2 1832.3 1847.7 1856.8 1234.7 1306.4 1313.0 1319.3 1336.9 5971.3 6110.6 6186.7 6196.5 6196.8 6586.9 6674.4 6720.6 6766.5 6842.5 6117.8 6155.6 6158.4 6167.5 6204.7 3586.5 3590.8 3595.3 3601.1 3617.4 6867.2 6920.8 6993.5 7047.2 7059.9 6872.2 6878.2 6896.8 6913.3 6941.5 6971.5 6988.5 7010.5 7081.3 7087.5 6611.9 6630.6 6670.3 6693.9 6737.5 6576.1 6613.9 6621.0 6676.2 6738.8 6867.9 7006.3 7007.1 7111.4 7129.0 5191.5 5195.6 5216.7 5273.6 5277.8 6485.3 6692.7 6697.5 6744.8 6783.2 6084.8 6537.4 6611.5 6619.3 6635.4 6471.9 6517.7 6550.4 6568.4 6623.5 6323.4 6473.8 6486.6 6513.8 6564.7 6556.5 6641.7 6663.0 6695.8 6714.9 6365.4 6368.6 6486.6 6560.1 6567.3 16:30:55 INFO - PID 11406 | 16:30:55 INFO - PID 11406 | [#5] yui.html Cycles:180 Average:28533.93 Median:18618.21 stddev:42593.60 (228.8%) stddev-sans-first:42662.70 16:30:55 INFO - PID 11406 | Values: 942.5 956.9 961.8 965.1 970.3 20171.5 20705.1 20729.4 20748.9 20771.4 19649.8 19683.3 19834.2 19866.6 19867.1 988.8 1002.9 1005.8 1014.1 1014.7 1063.0 1063.7 1065.2 1070.1 1070.9 1071.1 1102.1 1102.4 1106.6 1109.8 1104.3 1105.2 1106.4 1106.8 1107.6 795.8 813.8 816.7 817.9 821.8 21231.9 21496.9 21503.9 21538.4 21540.7 23089.1 23267.8 23562.4 23680.0 23917.6 18071.8 18338.7 18602.2 18719.8 18734.4 3974.9 3999.2 4003.2 4003.5 4005.8 137300.0 162029.0 162534.0 162572.0 166702.0 18508.6 18634.3 18871.2 18922.2 19147.1 33615.5 33676.7 33952.3 34225.0 34525.1 9894.4 9981.1 10001.0 10011.1 10043.6 4701.5 4729.7 4795.5 4842.6 4878.6 6169.3 6240.5 6242.2 6244.0 6273.3 1240.8 1274.3 1301.1 1304.5 1308.1 728.6 738.3 741.8 757.2 768.6 12258.4 12729.0 12756.0 13766.3 14003.8 734.9 736.6 747.8 753.1 755.3 5815.9 5834.1 5870.1 5893.6 5942.2 147438.3 148251.0 149395.0 150496.0 152755.0 132248.0 132570.3 133396.0 135317.0 141412.3 115036.0 115237.4 116314.0 117009.0 118208.0 11100.6 11263.7 11271.4 11325.7 11538.9 56265.4 57211.7 57585.0 57734.7 58294.0 70568.6 73621.3 74764.3 75001.6 75290.0 17785.7 17895.7 17935.8 18068.5 18141.5 19532.7 19982.8 20260.6 20504.9 20619.2 17253.6 17318.9 17360.7 17399.6 17562.6 17371.7 17602.4 17712.6 17761.0 17781.8 17871.9 18191.4 18218.4 18224.1 18426.8 12087.6 12134.6 12141.2 12256.9 12603.4 12192.2 12209.0 12261.9 12367.9 12571.1 16:30:55 INFO - PID 11406 | -------- Summary: end -------- 16:30:55 INFO - PID 11406 | 16:30:55 INFO - PID 11406 | [Parent 11406, Gecko_IOThread] WARNING: waitpid failed pid:11457 errno:10: file /builds/worker/workspace/build/src/ipc/chromium/src/base/process_util_posix.cc, line 276 16:30:55 INFO - TEST-INFO | 11406: exit 0 16:30:55 INFO - TEST-OK | dromaeo_css | took 1108664ms 16:30:55 INFO - TEST-START | kraken 16:30:55 INFO - Initialising browser for kraken test... 16:30:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37918/getInfo.html -profile /tmp/tmpVFzEA5/profile 16:30:55 INFO - TEST-INFO | started process 11791 (/builds/slave/test/build/application/firefox/firefox http://localhost:37918/getInfo.html) 16:31:01 INFO - TEST-INFO | 11791: exit 0 16:31:01 INFO - Browser initialized. 16:31:01 INFO - Running cycle 1/1 for kraken test... 16:31:01 INFO - Using env: {'DISPLAY': ':0', 16:31:01 INFO - 'HOME': '/home/cltbld', 16:31:01 INFO - 'JSGC_DISABLE_POISONING': '1', 16:31:01 INFO - 'LANG': 'en_US.UTF-8', 16:31:01 INFO - 'LANGUAGE': 'en_US:en', 16:31:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:31:01 INFO - 'LOGNAME': 'cltbld', 16:31:01 INFO - 'MAIL': '/var/mail/cltbld', 16:31:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:31:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:31:01 INFO - 'MOZ_CRASHREPORTER': '1', 16:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:31:01 INFO - 'MOZ_NO_REMOTE': '1', 16:31:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:01 INFO - 'NO_EM_RESTART': '1', 16:31:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:01 INFO - 'PWD': '/builds/slave/test', 16:31:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:31:01 INFO - 'RUST_BACKTRACE': 'full', 16:31:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:31:01 INFO - 'SHELL': '/bin/bash', 16:31:01 INFO - 'SHLVL': '1', 16:31:01 INFO - 'TERM': 'linux', 16:31:01 INFO - 'TMOUT': '86400', 16:31:01 INFO - 'USER': 'cltbld', 16:31:01 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590', 16:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:01 INFO - '_': '/tools/buildbot/bin/python'} 16:31:01 INFO - TEST-INFO | started process 12028 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVFzEA5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:31:02 INFO - PID 12028 | 16:31:02 INFO - PID 12028 | (/builds/slave/test/build/application/firefox/firefox:12078): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:31:02 INFO - PID 12028 | 16:31:12 INFO - PID 12028 | RSS: Main: 144547840 16:31:12 INFO - PID 12028 | 16:31:15 INFO - PID 12028 | Cycle 1(1): loaded http://localhost:37918/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37918/tests/kraken/driver.html?testName=audio-beat-detection) 16:31:16 INFO - PID 12028 | RSS: Main: 150204416 16:31:16 INFO - PID 12028 | 16:31:18 INFO - PID 12028 | Cycle 1(1): loaded http://localhost:37918/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37918/tests/kraken/driver.html?testName=audio-dft) 16:31:19 INFO - PID 12028 | RSS: Main: 151691264 16:31:19 INFO - PID 12028 | 16:31:22 INFO - PID 12028 | Cycle 1(1): loaded http://localhost:37918/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37918/tests/kraken/driver.html?testName=audio-fft) 16:31:22 INFO - PID 12028 | RSS: Main: 148201472 16:31:22 INFO - PID 12028 | 16:31:24 INFO - PID 12028 | Cycle 1(1): loaded http://localhost:37918/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37918/tests/kraken/driver.html?testName=audio-oscillator) 16:31:25 INFO - PID 12028 | RSS: Main: 149413888 16:31:25 INFO - PID 12028 | 16:31:27 INFO - PID 12028 | Cycle 1(1): loaded http://localhost:37918/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37918/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:31:28 INFO - PID 12028 | RSS: Main: 148795392 16:31:28 INFO - PID 12028 | 16:31:31 INFO - PID 12028 | Cycle 1(1): loaded http://localhost:37918/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37918/tests/kraken/driver.html?testName=imaging-darkroom) 16:31:31 INFO - PID 12028 | RSS: Main: 148885504 16:31:31 INFO - PID 12028 | 16:31:35 INFO - PID 12028 | Cycle 1(1): loaded http://localhost:37918/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37918/tests/kraken/driver.html?testName=imaging-desaturate) 16:31:36 INFO - PID 12028 | RSS: Main: 150061056 16:31:36 INFO - PID 12028 | 16:31:38 INFO - PID 12028 | Cycle 1(1): loaded http://localhost:37918/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37918/tests/kraken/driver.html?testName=json-parse-financial) 16:31:39 INFO - PID 12028 | RSS: Main: 152289280 16:31:39 INFO - PID 12028 | 16:31:41 INFO - PID 12028 | Cycle 1(1): loaded http://localhost:37918/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37918/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:31:41 INFO - PID 12028 | RSS: Main: 151982080 16:31:41 INFO - PID 12028 | 16:31:43 INFO - PID 12028 | Cycle 1(1): loaded http://localhost:37918/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37918/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:31:43 INFO - PID 12028 | RSS: Main: 151826432 16:31:43 INFO - PID 12028 | 16:31:45 INFO - PID 12028 | Cycle 1(1): loaded http://localhost:37918/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37918/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:31:46 INFO - PID 12028 | RSS: Main: 148627456 16:31:46 INFO - PID 12028 | 16:31:48 INFO - PID 12028 | Cycle 1(1): loaded http://localhost:37918/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37918/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:31:49 INFO - PID 12028 | RSS: Main: 148451328 16:31:49 INFO - PID 12028 | 16:31:52 INFO - PID 12028 | Cycle 1(1): loaded http://localhost:37918/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37918/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:31:52 INFO - PID 12028 | RSS: Main: 148979712 16:31:52 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | Cycle 1(1): loaded http://localhost:37918/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37918/tests/kraken/driver.html?testName=ai-astar) 16:31:54 INFO - PID 12028 | RSS: Main: 150654976 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | __start_tp_report 16:31:54 INFO - PID 12028 | _x_x_mozilla_page_load 16:31:54 INFO - PID 12028 | _x_x_mozilla_page_load_details 16:31:54 INFO - PID 12028 | |i|pagename|runs| 16:31:54 INFO - PID 12028 | |0;ai-astar;107;105;105;106;105;106;109;105;105;106 16:31:54 INFO - PID 12028 | |1;audio-beat-detection;118;113;118;110;141;112;134;170;112;115 16:31:54 INFO - PID 12028 | |2;audio-dft;161;165;163;162;162;162;161;162;165;170 16:31:54 INFO - PID 12028 | |3;audio-fft;76;72;73;75;73;74;73;73;75;73 16:31:54 INFO - PID 12028 | |4;audio-oscillator;80;79;78;79;79;78;77;78;78;79 16:31:54 INFO - PID 12028 | |5;imaging-gaussian-blur;114;113;114;114;114;113;113;111;113;115 16:31:54 INFO - PID 12028 | |6;imaging-darkroom;149;149;149;149;149;150;150;150;150;150 16:31:54 INFO - PID 12028 | |7;imaging-desaturate;94;94;94;94;94;93;94;102;95;94 16:31:54 INFO - PID 12028 | |8;json-parse-financial;45;50;45;45;45;46;44;43;43;44 16:31:54 INFO - PID 12028 | |9;json-stringify-tinderbox;38;37;38;37;38;38;38;38;38;37 16:31:54 INFO - PID 12028 | |10;stanford-crypto-aes;74;71;72;71;70;72;71;73;74;73 16:31:54 INFO - PID 12028 | |11;stanford-crypto-ccm;110;102;104;103;107;103;105;104;107;108 16:31:54 INFO - PID 12028 | |12;stanford-crypto-pbkdf2;159;153;149;151;151;154;153;150;155;157 16:31:54 INFO - PID 12028 | |13;stanford-crypto-sha256-iterative;64;62;63;60;60;58;61;59;60;59 16:31:54 INFO - PID 12028 | __end_tp_report 16:31:54 INFO - PID 12028 | __start_cc_report 16:31:54 INFO - PID 12028 | _x_x_mozilla_cycle_collect,732 16:31:54 INFO - PID 12028 | __end_cc_report 16:31:54 INFO - PID 12028 | __startTimestamp1506295914750__endTimestamp 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | ------- Summary: start ------- 16:31:54 INFO - PID 12028 | Number of tests: 14 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | [#0] ai-astar Cycles:10 Average:105.90 Median:106.00 stddev:1.29 (1.2%) stddev-sans-first:1.30 16:31:54 INFO - PID 12028 | Values: 107.0 105.0 105.0 106.0 105.0 106.0 109.0 105.0 105.0 106.0 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:118.00 stddev:19.04 (16.1%) stddev-sans-first:20.06 16:31:54 INFO - PID 12028 | Values: 118.0 113.0 118.0 110.0 141.0 112.0 134.0 170.0 112.0 115.0 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | [#2] audio-dft Cycles:10 Average:163.30 Median:162.50 stddev:2.75 (1.7%) stddev-sans-first:2.79 16:31:54 INFO - PID 12028 | Values: 161.0 165.0 163.0 162.0 162.0 162.0 161.0 162.0 165.0 170.0 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | [#3] audio-fft Cycles:10 Average:73.70 Median:73.50 stddev:1.25 (1.7%) stddev-sans-first:1.01 16:31:54 INFO - PID 12028 | Values: 76.0 72.0 73.0 75.0 73.0 74.0 73.0 73.0 75.0 73.0 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | [#4] audio-oscillator Cycles:10 Average:78.50 Median:79.00 stddev:0.85 (1.1%) stddev-sans-first:0.71 16:31:54 INFO - PID 12028 | Values: 80.0 79.0 78.0 79.0 79.0 78.0 77.0 78.0 78.0 79.0 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:114.00 stddev:1.07 (0.9%) stddev-sans-first:1.12 16:31:54 INFO - PID 12028 | Values: 114.0 113.0 114.0 114.0 114.0 113.0 113.0 111.0 113.0 115.0 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | [#6] imaging-darkroom Cycles:10 Average:149.50 Median:150.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 16:31:54 INFO - PID 12028 | Values: 149.0 149.0 149.0 149.0 149.0 150.0 150.0 150.0 150.0 150.0 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:94.00 stddev:2.57 (2.7%) stddev-sans-first:2.71 16:31:54 INFO - PID 12028 | Values: 94.0 94.0 94.0 94.0 94.0 93.0 94.0 102.0 95.0 94.0 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | [#8] json-parse-financial Cycles:10 Average:45.00 Median:45.00 stddev:2.00 (4.4%) stddev-sans-first:2.12 16:31:54 INFO - PID 12028 | Values: 45.0 50.0 45.0 45.0 45.0 46.0 44.0 43.0 43.0 44.0 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | [#9] json-stringify-tinderbox Cycles:10 Average:37.70 Median:38.00 stddev:0.48 (1.3%) stddev-sans-first:0.50 16:31:54 INFO - PID 12028 | Values: 38.0 37.0 38.0 37.0 38.0 38.0 38.0 38.0 38.0 37.0 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | [#10] stanford-crypto-aes Cycles:10 Average:72.10 Median:72.50 stddev:1.37 (1.9%) stddev-sans-first:1.27 16:31:54 INFO - PID 12028 | Values: 74.0 71.0 72.0 71.0 70.0 72.0 71.0 73.0 74.0 73.0 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | [#11] stanford-crypto-ccm Cycles:10 Average:105.30 Median:106.00 stddev:2.58 (2.4%) stddev-sans-first:2.11 16:31:54 INFO - PID 12028 | Values: 110.0 102.0 104.0 103.0 107.0 103.0 105.0 104.0 107.0 108.0 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.20 Median:153.50 stddev:3.16 (2.1%) stddev-sans-first:2.55 16:31:54 INFO - PID 12028 | Values: 159.0 153.0 149.0 151.0 151.0 154.0 153.0 150.0 155.0 157.0 16:31:54 INFO - PID 12028 | 16:31:54 INFO - PID 12028 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.60 Median:60.50 stddev:1.90 (3.1%) stddev-sans-first:1.56 16:31:54 INFO - PID 12028 | Values: 64.0 62.0 63.0 60.0 60.0 58.0 61.0 59.0 60.0 59.0 16:31:54 INFO - PID 12028 | -------- Summary: end -------- 16:31:54 INFO - PID 12028 | 16:31:55 INFO - PID 12028 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:31:55 INFO - TEST-INFO | 12028: exit 0 16:31:55 INFO - TEST-OK | kraken | took 59514ms 16:31:55 INFO - SUITE-END | took 1168s 16:31:55 INFO - Completed test suite (00:19:28) 16:31:55 INFO - javascript benchmark 16:31:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9126.237214924455, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3812.904677383066, 3819.1979684266303, 3826.234753049389, 3838.7888666123363, 3879.3211188042105, 6232.940400131977, 6283.874322513547, 6357.30069692334, 6426.48588112951, 6445.742170313182, 6368.426841584256, 6411.839704007402, 6418.71115799789, 6421.197350331208, 6432.0, 5635.380108188096, 5711.942880571193, 5737.512311453532, 5744.0, 5804.622699524539, 5774.220480235156, 5798.072308430652, 5827.242458480413, 5830.970845145789, 5838.854028649296, 923.0129250122781, 5698.059820129673, 5762.53899688026, 5856.853578660525, 5864.530837533009, 5700.543956483471, 5705.315362156568, 5717.171010203498, 5828.795992140254, 5834.154047663065, 5679.204911312419, 5693.174489699012, 5759.2512973313205, 5760.567957403212, 5764.827055188351, 6307.842303942392, 6323.083152942843, 6329.873402531924, 6351.745930162834, 6420.839479013015, 6899.586024838526, 6903.309669033107, 6998.195207551146, 7002.47481438889, 7023.157221133446, 6836.726530938806, 6895.655217239118, 6941.583504989717, 7033.4724895633035, 7043.401310888581, 6321.209848768904, 6321.841953951142, 6327.082573026885, 6328.683565821737, 6329.082283068929, 6905.620190889549, 6971.721131154697, 6972.1981972072845, 6981.685824137926, 7000.754973576002, 6701.597904125768, 6763.323667633295, 6783.084283621746, 6790.7623233186605, 6810.080639113756, 6893.896591551131, 6912.965435172792, 6989.965050174716, 7031.8945215821805, 7061.505694601328, 6758.155230596176, 6841.692123854438, 6850.6574671266235, 6868.038474613558, 6943.79168624942, 6743.763968261087, 6863.759768408182, 6890.2420733719255, 6910.619915904573, 6923.930760692429, 4981.576565991859, 4994.225894986283, 4995.25071239317, 5006.599472042228, 5058.747062646853, 4516.0, 4547.8635640930825, 4585.1746685596245, 4605.907881842344, 4618.815247390134, 6264.561480696308, 6291.433770960635, 6347.809565713036, 6348.746050158034, 6355.983042713144, 6862.622555759382, 6887.690053947585, 6952.652367381611, 6966.12923384577, 6970.024196612479, 6223.844403889849, 6384.170057892537, 6412.807615771534, 6420.7431702731365, 6427.967860160669, 5584.692841893653, 5593.552515798727, 5648.350439699405, 5666.065099258576, 5669.6314739541795, 6999.195092564319, 7093.0424392707355, 7159.821004474929, 7197.388222001084, 7199.06412166415, 6950.165980082423, 6958.617276049866, 6991.265917078634, 7010.684519196547, 7017.824554385977, 7121.608311542915, 7125.8574828502105, 7133.857322853618, 7138.107736532934, 7245.347918687344, 6625.933740662532, 6730.966345168243, 6738.022986666808, 6839.384455398839, 6930.06444130036, 6867.725290988305, 6919.065926100114, 6920.896186557105, 6931.44548436134, 6964.442844572523, 6692.899606506004, 6754.662266886734, 6765.1205343305055, 6768.357006084414, 6778.728850846108, 6509.121268628674, 6614.636195009129, 6619.27188009309, 6639.302873198245, 6739.05653208561, 6587.736490540325, 6661.566998145105, 6680.632565208765, 6689.130413046468, 6693.564918280296, 6658.400743933069, 6658.966705166443, 6705.430038446591, 6757.256701762901, 6769.052332673528, 6474.482041436768, 6518.120053792867, 6566.4418524425955, 6590.242122155822, 6598.472122230306], "value": 6262.300445171717, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [951.3388195204336, 954.8376775948088, 990.8563258327541, 1013.8023085498329, 1016.2124353625939, 45761.0, 45938.31092533615, 46165.7691711541, 46291.07417851641, 46533.06933861325, 43336.0, 43421.0, 43429.7828510857, 43508.0, 43584.78207608966, 7713.652885620132, 7842.254985776342, 7868.567228802413, 7870.685172593089, 7890.960545197266, 7592.430567707416, 7664.463487555873, 7673.769786906401, 7702.383809295271, 7717.8070548236465, 7751.0, 8631.180037896389, 8649.178328058824, 8651.351148663844, 8654.437461564978, 8474.152584741538, 8506.106858779833, 8528.74413767588, 8559.358048146352, 8569.228769410784, 8228.465149765307, 8390.035145958233, 8461.492310461392, 8477.109903460172, 8478.533680647563, 41519.7924010381, 42126.57873421257, 42246.366304505456, 42253.36619950703, 42268.0, 3635.5455568053994, 3670.0274427276795, 3675.0996005978704, 3679.1170119171475, 3682.742208045438, 34478.82760586181, 34604.307913841585, 34613.307733845184, 34654.653453465646, 34654.826725866464, 5429.375621803464, 5449.264349312872, 5472.945270547283, 5491.945080549183, 5504.0643090674685, 207282.0, 207294.0, 207571.9999999985, 207593.0, 209225.0, 52638.47361526412, 52829.47170528323, 55535.16697249545, 56640.716796415756, 56841.0, 58710.0, 59190.70404648035, 59201.703991479764, 59464.405355945884, 59489.40510594839, 15130.697386052218, 15498.922505387402, 17357.65284694299, 17404.129793510274, 17422.128893555273, 11998.940005299917, 12039.819402708967, 12083.093767967262, 12116.878831211576, 12143.210691305037, 15652.4521641742, 15744.291506882219, 15856.52430427089, 15950.920245398698, 15954.601134971486, 6088.438263615746, 6100.847478812976, 6141.84645383869, 6178.567500274938, 6212.81361559154, 1056.4400867540216, 1062.1768129699544, 1068.695421804782, 1079.9784004320027, 1092.8032954068344, 25204.84120120196, 25864.48271034569, 25886.352841178745, 26010.6098408524, 26072.34819129499, 1175.0834349207632, 1185.6265276437894, 1186.4423720851187, 1186.786378451887, 1187.352892673495, 13452.46190152383, 13496.595102146952, 13677.452901883813, 13768.65578360549, 13881.236531990635, 278548.0, 280787.0, 280907.0, 280988.0, 281057.0, 58389.70805146032, 58620.41379586235, 59528.404715953155, 63038.68480657567, 63136.36863631305, 1472.4699108320997, 1484.1169504145016, 1493.1265209852359, 1502.4891536877296, 1509.7584386498108, 31930.36139277248, 32027.199320017186, 32028.19929501781, 32320.838395808812, 32408.35183296368, 40845.591544085364, 42217.0, 42227.3665895018, 42519.0, 42585.36121958235, 182941.0, 184506.0, 184987.0, 185764.0, 186090.06954965138, 38520.0, 38546.421803672405, 38601.0, 38619.613803862725, 38891.2221755569, 47143.52856471392, 47313.763431182626, 47507.0, 47597.76201118972, 47813.28280075732, 33309.83345083259, 34830.0, 34867.65132348644, 34987.82506087453, 35246.11884702903, 34803.477947830295, 35055.649443506256, 35156.0, 35191.47212791861, 35368.646313536534, 41319.0, 41583.1683366337, 41797.37303940383, 41802.37296440617, 41953.16093678171, 34100.0, 34366.0, 34465.96602101941, 34753.65246347504, 34856.302873942885, 25720.228393148234, 25844.870775646003, 25999.61000584955, 26040.34899127537, 26168.60747088757], "value": 18736.813215809427, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.1587459567056, 607.6141650052216, 614.5421660862658, 625.8935980883249, 635.1045026512617, 23380.298591042276, 23403.76596234037, 24351.14770983016, 24368.26895193147, 24421.267361979124, 25207.117750878722, 25900.611490827654, 26002.219933401982, 26064.218073457778, 26092.34769130768, 6542.0, 6749.93250067498, 6759.459243260552, 6764.966175169117, 6778.356056174655, 6459.806205813809, 6539.280679125292, 6661.933380666204, 6689.264180940093, 6721.966390168042, 6676.499262555303, 6702.731890724365, 6702.966485167567, 6722.0, 6723.798286051427, 6452.0, 6627.436667883236, 6651.80044598658, 6670.366315200048, 6674.566153200027, 6209.099680546341, 6216.191895053659, 6232.065190221458, 6235.158253635746, 6243.906341404882, 29011.0, 29151.708482915325, 29309.26726831825, 29349.853250733824, 29509.852450737824, 2218.4786575154826, 2259.4464356232666, 2261.2424837679396, 2261.5929132756096, 2262.513559584681, 19551.511212219666, 20624.484387890272, 20706.79293207064, 20723.378298651063, 20804.791952080435, 2475.8885850136785, 2493.6010238361773, 2508.4481414089055, 2536.644869718241, 2545.9363515912064, 129930.0, 135858.32070839682, 149494.0, 151155.0, 155019.0, 15434.84565154334, 15926.929499165035, 16143.757843632355, 16604.335826566803, 16815.579610509834, 26065.0, 26131.346716332242, 26138.6079208812, 26144.346391339986, 26227.73772262291, 10496.317739346918, 10902.727431814268, 11023.17326200538, 11112.944435277772, 11120.499577519031, 9848.409095454295, 9948.303618746619, 9974.551145198484, 10088.798224035478, 10105.797884042278, 9626.671054665485, 9812.509374531388, 9865.309428339948, 9917.107460328605, 10073.395596264101, 2835.943281134366, 2905.680375158731, 2921.4887394706266, 2988.357503136836, 3049.557814116941, 1042.4943902207424, 1052.4685034057904, 1052.6736711619421, 1059.5073290919759, 1064.3932958213898, 20551.691724624143, 21216.575668486545, 21221.89389053045, 21425.0, 21489.032993515328, 1098.8406681031206, 1100.983485247722, 1113.3932007056173, 1121.6522877907876, 1124.533318672758, 11446.885531144748, 11622.476988535536, 11659.0, 11661.88338116625, 11691.473883675255, 602476.0, 603060.0, 610813.0, 611121.0, 616072.0, 125783.371083144, 127103.36448317699, 128380.0, 130655.0, 131806.0, 1116.2855772305732, 1204.265398107154, 1214.684182112657, 1228.336698182989, 1258.704204511939, 21855.235066773224, 22078.88960555187, 22325.888370558045, 22388.664170037137, 22399.32802015942, 62592.68703656452, 63865.0, 65013.674931626934, 65392.673036634515, 66417.66791166013, 81292.59353703193, 81634.18365816266, 82109.0, 83034.58482707548, 84244.73632895389, 15303.46437874669, 15556.455524056604, 15721.292541835874, 15727.685446290781, 15949.760753588473, 24186.274411767674, 24269.878650606635, 24318.27045188647, 24438.0, 24836.627450588618, 19837.0, 19847.206111755368, 19863.0, 19979.300724474575, 20039.398818035483, 13794.241316727675, 14469.0, 16321.918390407973, 17864.642707145525, 17932.820671793113, 18581.535461613566, 19228.423147305602, 19438.0, 19693.901530492254, 20151.294704685264, 15196.772048419503, 15399.692006160038, 15488.767668485207, 15496.457623983106, 15581.610459738597, 19209.231630734615, 19277.614447711247, 19446.902765486648, 19470.221191152195, 19478.415647530597], "value": 12792.017691413937, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1442.949496767613, 1497.970040599188, 1500.2798656644804, 1510.403390660689, 1512.5311153542402, 16900.492985210432, 17387.478375648752, 17463.91268043658, 17479.213435395406, 17548.82451175488, 17012.82987170128, 17070.48788536346, 17122.05828679422, 17127.486175414757, 17145.31418743252, 2796.384795345031, 2805.523061079616, 2867.612872262239, 2870.382867683448, 2872.8707208175683, 2757.6277202577694, 2792.5531914893622, 2795.7483826455614, 2800.0, 2830.80184387093, 2872.626558547386, 2910.519764238898, 2911.0539074800668, 2911.7233862783, 2912.504874171396, 2742.191053639181, 2783.526800443909, 2788.9721102788917, 2794.259521226866, 2809.381935974093, 2727.6726792784993, 2729.890804367823, 2737.986310068437, 2740.739629735172, 2742.026580563911, 16151.273192706356, 16562.917185414117, 16662.58343541409, 16753.329866805314, 16755.91622041894, 8139.837203255961, 8451.619677114544, 8456.36577256702, 8461.365397595144, 8464.1112683168, 5602.831915042514, 5783.277090363705, 5822.184894114827, 5901.8819623607715, 6067.666278354689, 2087.4363921741196, 2140.646793279107, 2145.249162793026, 2196.8681879087144, 2226.365485836529, 30495.0, 30763.538546921813, 30770.0, 30801.0, 30854.999999999774, 7088.0, 7094.467914906402, 7112.573245605176, 7178.748743793943, 7283.126024876943, 18879.90560047191, 18996.905015474833, 19113.52216194606, 19142.138603762865, 19177.712334314998, 8390.28682561989, 8399.748007559783, 8410.579471026424, 8419.0, 8427.831443371098, 7292.41660667156, 7710.344620707303, 7716.961415193, 7830.177831327628, 7838.921610783933, 5716.914246286309, 5720.2849643794525, 5775.422457754191, 5836.620619659709, 5836.941630583725, 1607.5257798949337, 1661.5264649574813, 1661.9584510387094, 1672.4230140601474, 1691.8984860908386, 1020.2807021050141, 1023.7389465686351, 1033.5555711044324, 1038.600138946511, 1054.6835949215206, 9441.386309889835, 9451.243900488082, 9479.194268487256, 9494.905050949541, 9615.711528654152, 1054.288355360129, 1076.4025965589017, 1091.4324551233315, 1093.4587379247323, 1096.1011970184531, 5508.366537848118, 5537.0, 6093.847653808691, 6281.685915704196, 6307.590006649553, 24633.0, 25041.499170016497, 25172.24483265505, 25379.98480060777, 25426.0, 23686.407839804142, 24640.0, 24662.26013219606, 24832.37919052038, 24903.62644560333, 12528.81206781899, 12538.623841284776, 12792.872071279355, 12828.615141545955, 12829.422675979604, 9782.461964592016, 9893.159081478156, 9894.0, 9961.750956226153, 10002.799944001225, 20230.0, 20563.897180514003, 21310.467238319165, 21405.35783926485, 21569.568608627425, 21835.344939651837, 21932.780672193076, 22133.889330553244, 22200.33398997968, 22257.554848903255, 12250.571230007263, 12465.5013799447, 12468.438920248793, 12477.188982716094, 12504.874951250371, 15520.068795872285, 15595.376184952474, 15626.921865391056, 15778.368865245262, 15784.684306314039, 12412.875871241533, 12542.49830006826, 12916.741665166832, 12927.806082908952, 12951.222926624432, 12175.878241217475, 12247.938760306142, 12355.93822030884, 12497.875021250034, 12592.803683650038, 10996.395198263852, 11233.55065797359, 11265.493052812806, 11279.92840680134, 11293.153013523854, 8462.788430289293, 8629.13708629132, 8708.56457177126, 8724.127587241224, 8748.168923952215, 8552.914470855212, 8576.742697719079, 8646.697365592425, 8706.521141337287, 8741.650333986569], "value": 7412.360241560802, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.9329280486343, 561.2843624378918, 563.0474072014456, 585.6164212440852, 586.8386193796705, 6133.938660613392, 6222.191115155029, 6264.279607845094, 6326.493880489561, 6330.33531479195, 6225.661482781201, 6244.781432649858, 6288.025356069817, 6296.307406185316, 6341.809745707625, 5218.504242097014, 5220.216967454884, 5250.65870718402, 5269.604779641523, 5273.288106105684, 4918.508149185071, 5016.573591244749, 5192.974035129819, 5237.764300606482, 5248.501392367738, 4963.106640804654, 4994.625403094764, 5017.448080711119, 5031.723255220962, 5032.270320803481, 4989.950100498985, 4996.475370086125, 4997.125503036954, 5020.196768517038, 5048.6465947383695, 4554.954450455486, 4570.862874113782, 4579.63362930968, 4591.6097131743845, 4606.239970404879, 6147.692615369258, 6206.906896396557, 6212.0, 6233.5013198944425, 6293.0, 5931.466168044896, 5945.851353716149, 5972.25346831646, 5980.641161530322, 5982.102684597302, 6540.640264785435, 6573.244076931166, 6645.435138013275, 6683.699233534503, 6731.93268067318, 4836.202026665596, 5090.720010399426, 5102.234664800272, 5103.591712663015, 5146.665466744649, 6874.793756187322, 6881.311868813129, 6984.650767461658, 6995.475339349569, 7001.124859392576, 5216.113260745682, 5223.738813059408, 5225.451327610622, 5277.577793776489, 5283.788648454019, 5303.867403314871, 5311.123664595376, 5313.973430132824, 5353.0, 5359.705216213068, 5879.412058794086, 5900.852478687982, 5923.7926672566255, 5961.105834124916, 5974.61165024272, 6712.053504483645, 6720.8319792005595, 6743.224529179109, 6747.156605424322, 6761.357671021246, 1819.0995457248746, 1830.213008406398, 1832.3128826689992, 1847.6951303035119, 1856.832885040353, 1234.7283597608512, 1306.405585458614, 1312.9737405251842, 1319.327143156997, 1336.9398377073055, 5971.253593300838, 6110.602810817282, 6186.721597528122, 6196.47329976948, 6196.783112591038, 6586.868262634721, 6674.399304062657, 6720.630365329954, 6766.458683305324, 6842.486813488908, 6117.84705382369, 6155.599886007485, 6158.414950579688, 6167.506599472032, 6204.689765511706, 3586.4799604057266, 3590.7791350940806, 3595.280943811248, 3601.1177261571083, 3617.4212126059706, 6867.2102708189195, 6920.792376228721, 6993.510454268254, 7047.154341479055, 7059.89410158848, 6872.209695884937, 6878.209005964278, 6896.793096207121, 6913.306239971139, 6941.514094013371, 6971.5359774446915, 6988.545744526589, 7010.544314619636, 7081.291870812877, 7087.539309944837, 6611.867762644816, 6630.569013014138, 6670.266270710121, 6693.866122677617, 6737.5407976089355, 6576.145101136822, 6613.933860661525, 6621.0, 6676.165479315086, 6738.831529211809, 6867.896981545381, 7006.2643422439905, 7007.124109486314, 7111.395531379891, 7128.964355178398, 5191.506806853343, 5195.5843532518065, 5216.739163041757, 5273.578113750814, 5277.788888444419, 6485.346236709609, 6692.6988285526295, 6697.464202863662, 6744.785938531111, 6783.18601767791, 6084.787032453843, 6537.444317232898, 6611.471082313499, 6619.271880093283, 6635.4359879410795, 6471.902921456276, 6517.6638789049075, 6550.410463058348, 6568.441682457044, 6623.536352455475, 6323.447118074979, 6473.805785826433, 6486.643234622141, 6513.804585862432, 6564.671766411756, 6556.508261880283, 6641.667916604247, 6663.033860090357, 6695.765648202289, 6714.899276510759, 6365.363463653597, 6368.64972426521, 6486.643234622141, 6560.114384558215, 6567.277599464151], "value": 5209.621067520668, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [942.5193151492737, 956.9138777510022, 961.8124465729181, 965.0542468380982, 970.2916870684403, 20171.495712607193, 20705.06827192776, 20729.378118656463, 20748.89625551872, 20771.376858694224, 19649.803501964976, 19683.311084112058, 19834.206631734713, 19866.602667946634, 19867.10598023089, 988.8071825993934, 1002.8896821349664, 1005.7686732051633, 1014.0822555587206, 1014.6702321745425, 1062.9893701062967, 1063.7393838509577, 1065.2223876570108, 1070.1118072000222, 1070.8982646648594, 1071.1216802222182, 1102.0852692265441, 1102.404701461214, 1106.634810512533, 1109.8002359575273, 1104.309806371018, 1105.2042529378834, 1106.419129956771, 1106.8007758603449, 1107.5514416661224, 795.7732046366758, 813.7640084375583, 816.6774124220959, 817.9345652347798, 821.7740121466585, 21231.893840530855, 21496.892515537478, 21503.892480537495, 21538.353849384544, 21540.676889846665, 23089.0764369425, 23267.767322326898, 23562.410939726473, 23680.0, 23917.641235381485, 18071.819281807275, 18338.724919126224, 18602.162902669414, 18719.812801871944, 18734.437966861016, 3974.86087986922, 3999.240144372589, 4003.179348233605, 4003.4795476588147, 4005.819738111792, 137300.0, 162029.0, 162534.0, 162572.0, 166702.0, 18508.629827403645, 18634.25462981493, 18871.245150193838, 18922.24311027571, 19147.13837877299, 33615.49576756351, 33676.66323336784, 33952.32095358079, 34225.0, 34525.13687157841, 9894.356866803635, 9981.101700846959, 10001.049900259464, 10011.099001089937, 10043.648472303434, 4701.506341834127, 4729.7398643074275, 4795.472498025214, 4842.612590992712, 4878.585320247747, 6169.290531588835, 6240.469560087353, 6242.188515492957, 6244.0, 6273.278572964076, 1240.838690970168, 1274.2672963046286, 1301.1477482249143, 1304.4716889659703, 1308.117021010814, 728.6211170191576, 738.2728012907282, 741.8219627289411, 757.1519897714485, 768.5734417398397, 12258.448369823202, 12729.0, 12756.0, 13766.31168441574, 14003.789943150861, 734.9081364829397, 736.6169591812228, 747.8317378589774, 753.1038064702985, 755.316438623047, 5815.88368232633, 5834.066549352084, 5870.14882841994, 5893.557983151195, 5942.168096466498, 147438.2628086874, 148251.0, 149395.0, 150496.0, 152755.0, 132248.0, 132570.33714831556, 133396.0, 135317.0, 141412.2929385367, 115036.0, 115237.42381288207, 116314.0, 117009.0, 118208.0, 11100.5559777608, 11263.66209013731, 11271.436428178395, 11325.660230193107, 11538.88461115378, 56265.43734562602, 57211.71394143169, 57584.984525541404, 57734.711326443095, 58294.0, 70568.6471567639, 73621.26378736144, 74764.25235747574, 75001.62499187469, 75290.0, 17785.73321400206, 17895.73156402681, 17935.820641793416, 18068.457946261635, 18141.45575632733, 19532.707009394588, 19982.800171998675, 20260.59478810386, 20504.897475512527, 20619.175232990514, 17253.56866078358, 17318.913405432893, 17360.73958890643, 17399.56501087483, 17562.560935976704, 17371.739423908904, 17602.38391656286, 17712.55718607045, 17761.0, 17781.822181778018, 17871.910640447233, 18191.363302284357, 18218.36235731743, 18224.088795560434, 18426.815731843046, 12087.609924858401, 12134.575289864812, 12141.210821296587, 12256.938715306367, 12603.432845522157, 12192.207506512048, 12209.023278137905, 12261.938690306491, 12367.93816030914, 12571.1200215984], "value": 9967.648455973485, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1377.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 105.0, 105.0, 106.0, 105.0, 106.0, 109.0, 105.0, 105.0, 106.0], "value": 105.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 113.0, 118.0, 110.0, 141.0, 112.0, 134.0, 170.0, 112.0, 115.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 165.0, 163.0, 162.0, 162.0, 162.0, 161.0, 162.0, 165.0, 170.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 72.0, 73.0, 75.0, 73.0, 74.0, 73.0, 73.0, 75.0, 73.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 78.0, 79.0, 79.0, 78.0, 77.0, 78.0, 78.0, 79.0], "value": 78.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 114.0, 114.0, 113.0, 113.0, 111.0, 113.0, 115.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 149.0, 149.0, 150.0, 150.0, 150.0, 150.0, 150.0], "value": 149.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 102.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 50.0, 45.0, 45.0, 45.0, 46.0, 44.0, 43.0, 43.0, 44.0], "value": 45.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 38.0, 37.0, 38.0, 38.0, 38.0, 38.0, 38.0, 37.0], "value": 37.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 71.0, 72.0, 71.0, 70.0, 72.0, 71.0, 73.0, 74.0, 73.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 102.0, 104.0, 103.0, 107.0, 103.0, 105.0, 104.0, 107.0, 108.0], "value": 105.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 153.0, 149.0, 151.0, 151.0, 154.0, 153.0, 150.0, 155.0, 157.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 60.0, 60.0, 58.0, 61.0, 59.0, 60.0, 59.0], "value": 60.6, "unit": "score"}]}]} 16:31:55 INFO - Return code: 0 16:31:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:31:55 INFO - # TBPL SUCCESS # 16:31:55 INFO - Running post-action listener: _package_coverage_data 16:31:55 INFO - Running post-action listener: _resource_record_post_action 16:31:55 INFO - [mozharness: 2017-09-24 23:31:55.543817Z] Finished run-tests step (success) 16:31:55 INFO - Running post-run listener: _resource_record_post_run 16:31:55 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 389455872; Read time: 12; Write time: 267580 16:31:55 INFO - TinderboxPrint: CPU usage
13.1% 16:31:55 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:31:55 INFO - TinderboxPrint: I/O write bytes / time
389,455,872 / 267,580 16:31:55 INFO - TinderboxPrint: CPU idle
8,218.9 (86.9%) 16:31:55 INFO - TinderboxPrint: CPU user
1,187.7 (12.6%) 16:31:55 INFO - TinderboxPrint: Swap in / out
0 / 0 16:31:55 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 74768384; Read time: 0; Write time: 37868 16:31:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:31:55 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 0; Write bytes: 308318208; Read time: 0; Write time: 227140 16:31:56 INFO - Running post-run listener: _upload_blobber_files 16:31:56 INFO - Blob upload gear active. 16:31:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:31:56 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 16:31:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:31:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:31:56 INFO - (blobuploader) - INFO - Open directory for files ... 16:31:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:31:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:31:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:57 INFO - (blobuploader) - INFO - Done attempting. 16:31:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:31:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:31:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:58 INFO - (blobuploader) - INFO - Done attempting. 16:31:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:31:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:32:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:05 INFO - (blobuploader) - INFO - Done attempting. 16:32:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:32:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:32:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:06 INFO - (blobuploader) - INFO - Done attempting. 16:32:06 INFO - (blobuploader) - INFO - Iteration through files over. 16:32:06 INFO - Return code: 0 16:32:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:32:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:32:06 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d1371bcee61212187e480730478b15f1ebe1e6081e1b97023e96a3fb9e9e6bd41c2507ddf0c79c8babbf6f3f0ee2b490afa1d51435d7eae8da069ed4f88cee1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee75091d457fd78956ef5dac29c2e6c4a0fc1f26cb4452b7de920291dc66840dcd7bb1496239c8fc4b311419ea7ee3dcfbc0f697db71825cd44941321c2fa9a6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/638698949a98b6e8b60e1206785b3f01f8ea52ceed411db7671e00f88a52ae302f28dd09cfbfa80e5370e820effa221d2ad0e3c7359de634100e3e70ed5306f6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b216410dd72f008c78468a45f88e52989fd6e36f45f0200b4cac272daa7bf5ff652a3e4095b608ed2747d5355870aa3bcedc45dc9a0075a36aa8877d70e091f2"} 16:32:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:32:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:32:06 INFO - Contents: 16:32:06 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d1371bcee61212187e480730478b15f1ebe1e6081e1b97023e96a3fb9e9e6bd41c2507ddf0c79c8babbf6f3f0ee2b490afa1d51435d7eae8da069ed4f88cee1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee75091d457fd78956ef5dac29c2e6c4a0fc1f26cb4452b7de920291dc66840dcd7bb1496239c8fc4b311419ea7ee3dcfbc0f697db71825cd44941321c2fa9a6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/638698949a98b6e8b60e1206785b3f01f8ea52ceed411db7671e00f88a52ae302f28dd09cfbfa80e5370e820effa221d2ad0e3c7359de634100e3e70ed5306f6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b216410dd72f008c78468a45f88e52989fd6e36f45f0200b4cac272daa7bf5ff652a3e4095b608ed2747d5355870aa3bcedc45dc9a0075a36aa8877d70e091f2"} 16:32:06 INFO - Running post-run listener: copy_logs_to_upload_dir 16:32:06 INFO - Copying logs to upload dir... 16:32:06 INFO - mkdir: /builds/slave/test/build/upload/logs 16:32:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.936820 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-24 16:32:06.253205) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-24 16:32:06.259960) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d1371bcee61212187e480730478b15f1ebe1e6081e1b97023e96a3fb9e9e6bd41c2507ddf0c79c8babbf6f3f0ee2b490afa1d51435d7eae8da069ed4f88cee1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee75091d457fd78956ef5dac29c2e6c4a0fc1f26cb4452b7de920291dc66840dcd7bb1496239c8fc4b311419ea7ee3dcfbc0f697db71825cd44941321c2fa9a6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/638698949a98b6e8b60e1206785b3f01f8ea52ceed411db7671e00f88a52ae302f28dd09cfbfa80e5370e820effa221d2ad0e3c7359de634100e3e70ed5306f6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b216410dd72f008c78468a45f88e52989fd6e36f45f0200b4cac272daa7bf5ff652a3e4095b608ed2747d5355870aa3bcedc45dc9a0075a36aa8877d70e091f2"} build_url:https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011399 build_url: 'https://queue.taskcluster.net/v1/task/ce0ldRR8TaGxVM0-hHQ4zg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d1371bcee61212187e480730478b15f1ebe1e6081e1b97023e96a3fb9e9e6bd41c2507ddf0c79c8babbf6f3f0ee2b490afa1d51435d7eae8da069ed4f88cee1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee75091d457fd78956ef5dac29c2e6c4a0fc1f26cb4452b7de920291dc66840dcd7bb1496239c8fc4b311419ea7ee3dcfbc0f697db71825cd44941321c2fa9a6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/638698949a98b6e8b60e1206785b3f01f8ea52ceed411db7671e00f88a52ae302f28dd09cfbfa80e5370e820effa221d2ad0e3c7359de634100e3e70ed5306f6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b216410dd72f008c78468a45f88e52989fd6e36f45f0200b4cac272daa7bf5ff652a3e4095b608ed2747d5355870aa3bcedc45dc9a0075a36aa8877d70e091f2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-24 16:32:06.287923) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:32:06.288228) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=826874ccc97bd7bf541b4875000001d5-1506293177.244008-1012480590 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004546 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:32:06.373385) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-24 16:32:06.373703) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-24 16:32:06.374003) ========= ========= Total master_lag: 0.29 =========