builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-036 starttime: 1505507870.41 results: success (0) revision: 5b39f8dcc5cd6a38fe99564a9cfc90eedfad1639 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 13:37:50.413453) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 13:37:50.413906) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 13:37:50.414181) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 13:37:50.431724) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 13:37:50.432077) ========= 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=14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-15 13:37:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.1M=0s 2017-09-15 13:37:50 (69.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087516 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 13:37:50.537975) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 13:37:50.538297) ========= 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=14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019504 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 13:37:50.590831) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-15 13:37:50.591208) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5b39f8dcc5cd6a38fe99564a9cfc90eedfad1639 --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 5b39f8dcc5cd6a38fe99564a9cfc90eedfad1639 --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=14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-15 13:37:50,929 truncating revision to first 12 chars 2017-09-15 13:37:50,929 Setting DEBUG logging. 2017-09-15 13:37:50,929 attempt 1/10 2017-09-15 13:37:50,929 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5b39f8dcc5cd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-15 13:37:51,679 unpacking tar archive at: mozilla-central-5b39f8dcc5cd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.012464 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-15 13:37:51.622824) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 13:37:51.623157) ========= script_repo_revision: 5b39f8dcc5cd6a38fe99564a9cfc90eedfad1639 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 13:37:51.623531) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 13:37:51.623821) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 13:37:51.637095) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-15 13:37:51.637387) ========= /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=14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:37:52 INFO - MultiFileLogger online at 20170915 13:37:52 in /builds/slave/test 13:37:52 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 13:37:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:37:52 INFO - {'append_to_log': False, 13:37:52 INFO - 'base_work_dir': '/builds/slave/test', 13:37:52 INFO - 'blob_upload_branch': 'Firefox', 13:37:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:37:52 INFO - 'branch': 'Firefox', 13:37:52 INFO - 'buildbot_json_path': 'buildprops.json', 13:37:52 INFO - 'code_coverage': False, 13:37:52 INFO - 'config_files': ('talos/linux_config.py',), 13:37:52 INFO - 'default_actions': ('clobber', 13:37:52 INFO - 'read-buildbot-config', 13:37:52 INFO - 'download-and-extract', 13:37:52 INFO - 'populate-webroot', 13:37:52 INFO - 'create-virtualenv', 13:37:52 INFO - 'install', 13:37:52 INFO - 'setup-mitmproxy', 13:37:52 INFO - 'run-tests'), 13:37:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:37:52 INFO - 'disable_ccov_upload': False, 13:37:52 INFO - 'disable_stylo': False, 13:37:52 INFO - 'download_minidump_stackwalk': True, 13:37:52 INFO - 'download_symbols': 'ondemand', 13:37:52 INFO - 'enable_stylo': False, 13:37:52 INFO - 'enable_webrender': False, 13:37:52 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 13:37:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:37:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:37:52 INFO - 'gecko_profile': False, 13:37:52 INFO - 'gecko_profile_interval': 0, 13:37:52 INFO - 'installer_path': 'installer.exe', 13:37:52 INFO - 'log_level': 'info', 13:37:52 INFO - 'log_name': 'talos', 13:37:52 INFO - 'log_to_console': True, 13:37:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:37:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:37:52 INFO - 'opt_config_files': (), 13:37:52 INFO - 'pip_index': False, 13:37:52 INFO - 'suite': 'dromaeojs-e10s', 13:37:52 INFO - 'system_bits': '32', 13:37:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:37:52 INFO - 'title': 'talos-linux64-ix-036', 13:37:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:37:52 INFO - 'use_talos_json': True, 13:37:52 INFO - 'verify': 'False', 13:37:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:37:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:37:52 INFO - 'work_dir': 'build'} 13:37:52 INFO - [mozharness: 2017-09-15 20:37:52.120994Z] Running clobber step. 13:37:52 INFO - Running pre-action listener: _resource_record_pre_action 13:37:52 INFO - Running main action method: clobber 13:37:52 INFO - rmtree: /builds/slave/test/build 13:37:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:37:53 INFO - Running post-action listener: _resource_record_post_action 13:37:53 INFO - [mozharness: 2017-09-15 20:37:53.113454Z] Finished clobber step (success) 13:37:53 INFO - [mozharness: 2017-09-15 20:37:53.113551Z] Running read-buildbot-config step. 13:37:53 INFO - Running pre-action listener: _resource_record_pre_action 13:37:53 INFO - Running main action method: read_buildbot_config 13:37:53 INFO - Using buildbot properties: 13:37:53 INFO - { 13:37:53 INFO - "project": "", 13:37:53 INFO - "product": "firefox", 13:37:53 INFO - "who": "archaeopteryx@coole-files.de", 13:37:53 INFO - "installer_path": "public/build/target.tar.bz2", 13:37:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 13:37:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 13:37:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 13:37:53 INFO - "stage_platform": "linux64-pgo", 13:37:53 INFO - "basedir": "/builds/slave/test", 13:37:53 INFO - "buildnumber": 166, 13:37:53 INFO - "platform": "ubuntu64_hw", 13:37:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:37:53 INFO - "slavebuilddir": "test", 13:37:53 INFO - "taskId": "G_vWRwkuQvShCRFzJbVsxw", 13:37:53 INFO - "branch": "mozilla-central", 13:37:53 INFO - "script_repo_revision": "production", 13:37:53 INFO - "revision": "5b39f8dcc5cd6a38fe99564a9cfc90eedfad1639", 13:37:53 INFO - "slavename": "talos-linux64-ix-036", 13:37:53 INFO - "repo_path": "mozilla-central" 13:37:53 INFO - } 13:37:53 INFO - Finding installer, test and symbols from parent task. 13:37:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G_vWRwkuQvShCRFzJbVsxw'}, attempt #1 13:37:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G_vWRwkuQvShCRFzJbVsxw'}, attempt #1 13:37:54 INFO - Task dependencies: YkVsvYeGRWujvQ9mZm3eYw 13:37:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw'}, attempt #1 13:37:54 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.tar.bz2 13:37:54 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.test_packages.json 13:37:54 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.crashreporter-symbols.zip 13:37:54 INFO - Running post-action listener: _resource_record_post_action 13:37:54 INFO - [mozharness: 2017-09-15 20:37:54.473593Z] Finished read-buildbot-config step (success) 13:37:54 INFO - [mozharness: 2017-09-15 20:37:54.473809Z] Running download-and-extract step. 13:37:54 INFO - Running pre-action listener: _resource_record_pre_action 13:37:54 INFO - Running main action method: download_and_extract 13:37:54 INFO - mkdir: /builds/slave/test/build/tests 13:37:54 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')]} 13:37:54 INFO - trying https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.test_packages.json 13:37:54 INFO - Downloading https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 13:37:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 13:37:55 INFO - Downloaded 989 bytes. 13:37:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 13:37:55 INFO - Using the following test package requirements: 13:37:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 13:37:55 INFO - u'common': [u'target.common.tests.zip'], 13:37:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 13:37:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 13:37:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 13:37:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 13:37:55 INFO - u'mozbase': [u'target.common.tests.zip'], 13:37:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 13:37:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 13:37:55 INFO - u'web-platform': [u'target.common.tests.zip', 13:37:55 INFO - u'target.web-platform.tests.tar.gz'], 13:37:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 13:37:55 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 13:37:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.common.tests.zip 13:37:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:37:55 INFO - Fetch https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.common.tests.zip into memory 13:37:56 INFO - Content-Length response header: 55325104 13:37:56 INFO - Bytes received: 55325104 13:38:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.talos.tests.zip 13:38:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 13:38:06 INFO - Fetch https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.talos.tests.zip into memory 13:38:07 INFO - Content-Length response header: 14508749 13:38:07 INFO - Bytes received: 14508749 13:38: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')]} 13:38:08 INFO - trying https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.tar.bz2 13:38:08 INFO - Downloading https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 13:38:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 13:38:09 INFO - Downloaded 62221336 bytes. 13:38:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.tar.bz2 13:38:09 INFO - mkdir: /builds/slave/test/properties 13:38:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:38:09 INFO - Writing to file /builds/slave/test/properties/build_url 13:38:09 INFO - Contents: 13:38:09 INFO - build_url:https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.tar.bz2 13:38:09 INFO - Running post-action listener: _resource_record_post_action 13:38:09 INFO - Running post-action listener: find_tests_for_verification 13:38:09 INFO - Running post-action listener: set_extra_try_arguments 13:38:09 INFO - [mozharness: 2017-09-15 20:38:09.642454Z] Finished download-and-extract step (success) 13:38:09 INFO - [mozharness: 2017-09-15 20:38:09.642552Z] Running populate-webroot step. 13:38:09 INFO - Running pre-action listener: _resource_record_pre_action 13:38:09 INFO - Running main action method: populate_webroot 13:38:09 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:38:09 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:38:09 INFO - 'tests': ['tresize', 13:38:09 INFO - 'tcanvasmark']}, 13:38:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:38:09 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:38:09 INFO - 'tests': ['dromaeo_css', 13:38:09 INFO - 'kraken']}, 13:38:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:38:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:38:09 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:38:09 INFO - 'talos_options': ['--disable-stylo'], 13:38:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:38:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:38:09 INFO - 'tests': ['damp', 'tps']}, 13:38:09 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:38:09 INFO - 'talos_options': ['--disable-stylo'], 13:38:09 INFO - 'tests': ['damp', 'tps']}, 13:38:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:38:09 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:38:09 INFO - 'tests': ['dromaeo_dom']}, 13:38:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 13:38:09 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:38:09 INFO - 'tests': ['basic_compositor_video', 13:38:09 INFO - 'glvideo']}, 13:38:09 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 13:38:09 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 13:38:09 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:38:09 INFO - 'talos_options': ['--disable-stylo'], 13:38:09 INFO - 'tests': ['ts_paint_webext', 13:38:09 INFO - 'tp5o_webext']}, 13:38:09 INFO - 'other-e10s': {'tests': ['a11yr', 13:38:09 INFO - 'ts_paint', 13:38:09 INFO - 'tpaint', 13:38:09 INFO - 'sessionrestore', 13:38:09 INFO - 'sessionrestore_many_windows', 13:38:09 INFO - 'sessionrestore_no_auto_restore', 13:38:09 INFO - 'tabpaint']}, 13:38:09 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:38:09 INFO - 'tests': ['a11yr', 13:38:09 INFO - 'ts_paint', 13:38:09 INFO - 'tpaint', 13:38:09 INFO - 'sessionrestore', 13:38:09 INFO - 'sessionrestore_many_windows', 13:38:09 INFO - 'sessionrestore_no_auto_restore', 13:38:09 INFO - 'tabpaint']}, 13:38:09 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 13:38:09 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 13:38:09 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:38:09 INFO - 'tests': ['perf_reftest_singletons']}, 13:38:09 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:38:09 INFO - 'tests': ['bloom_basic']}, 13:38:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:38:09 INFO - 'tsvgr_opacity', 13:38:09 INFO - 'tart', 13:38:09 INFO - 'tscrollx', 13:38:09 INFO - 'tsvg_static']}, 13:38:09 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:38:09 INFO - 'tests': ['tsvgx', 13:38:09 INFO - 'tsvgr_opacity', 13:38:09 INFO - 'tart', 13:38:09 INFO - 'tscrollx', 13:38:09 INFO - 'tsvg_static']}, 13:38:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:38:09 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:38:09 INFO - 'talos_options': ['--disable-stylo'], 13:38:09 INFO - 'tests': ['tp5o']}, 13:38:09 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:38:09 INFO - 'talos_options': ['--mitmproxy', 13:38:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:38:09 INFO - '--firstNonBlankPaint'], 13:38:09 INFO - 'tests': ['tp6_google', 13:38:09 INFO - 'tp6_youtube', 13:38:09 INFO - 'tp6_amazon', 13:38:09 INFO - 'tp6_facebook']}, 13:38:09 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:38:09 INFO - 'talos_options': ['--disable-stylo', 13:38:09 INFO - '--mitmproxy', 13:38:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:38:09 INFO - '--firstNonBlankPaint'], 13:38:09 INFO - 'tests': ['tp6_google', 13:38:09 INFO - 'tp6_youtube', 13:38:09 INFO - 'tp6_amazon', 13:38:09 INFO - 'tp6_facebook']}, 13:38:09 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:38:09 INFO - 'talos_options': ['--stylo-threads=1', 13:38:09 INFO - '--mitmproxy', 13:38:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:38:09 INFO - '--firstNonBlankPaint'], 13:38:09 INFO - 'tests': ['tp6_google', 13:38:09 INFO - 'tp6_youtube', 13:38:09 INFO - 'tp6_amazon', 13:38:09 INFO - 'tp6_facebook']}, 13:38:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:38:09 INFO - 'talos_options': ['--xperf_path', 13:38:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:38:09 INFO - 'tests': ['tp5n']}, 13:38:09 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:38:09 INFO - 'talos_options': ['--disable-stylo', 13:38:09 INFO - '--xperf_path', 13:38:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:38:09 INFO - 'tests': ['tp5n']}}} 13:38:09 INFO - Running post-action listener: _resource_record_post_action 13:38:09 INFO - [mozharness: 2017-09-15 20:38:09.658097Z] Finished populate-webroot step (success) 13:38:09 INFO - [mozharness: 2017-09-15 20:38:09.658196Z] Running create-virtualenv step. 13:38:09 INFO - Running pre-action listener: _resource_record_pre_action 13:38:09 INFO - Running main action method: create_virtualenv 13:38:09 INFO - Creating virtualenv /builds/slave/test/build/venv 13:38:09 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:38:09 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 13:38:09 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 13:38:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:38:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:38:10 INFO - Installing distribute.............................................................................................................................................................................................done. 13:38:11 INFO - Installing pip...............done. 13:38:11 INFO - Return code: 0 13:38:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:38:11 INFO - Reading from file tmpfile_stdout 13:38:11 INFO - Output received: 13:38:11 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 13:38:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:38:11 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')]} 13:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:11 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')]} 13:38:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:11 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 0x1f7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2143a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ea090>, '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 0x22ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e4f90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', '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 13:38:11 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 13:38:11 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 13:38:11 INFO - Using env: {'DISPLAY': ':0', 13:38:11 INFO - 'HOME': '/home/cltbld', 13:38:11 INFO - 'LANG': 'en_US.UTF-8', 13:38:11 INFO - 'LANGUAGE': 'en_US:en', 13:38:11 INFO - 'LOGNAME': 'cltbld', 13:38:11 INFO - 'MAIL': '/var/mail/cltbld', 13:38:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:11 INFO - 'MOZ_NO_REMOTE': '1', 13:38:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:11 INFO - 'NO_EM_RESTART': '1', 13:38:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:11 INFO - 'PWD': '/builds/slave/test', 13:38:11 INFO - 'SHELL': '/bin/bash', 13:38:11 INFO - 'SHLVL': '1', 13:38:11 INFO - 'TERM': 'linux', 13:38:11 INFO - 'TMOUT': '86400', 13:38:11 INFO - 'USER': 'cltbld', 13:38:11 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', 13:38:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:11 INFO - '_': '/tools/buildbot/bin/python'} 13:38:12 INFO - Ignoring indexes: http://pypi.python.org/simple/ 13:38:12 INFO - Downloading/unpacking pip>=1.5 13:38:12 INFO - Running setup.py egg_info for package pip 13:38:12 INFO - warning: no files found matching 'pip/cacert.pem' 13:38:12 INFO - warning: no files found matching '*.html' under directory 'docs' 13:38:12 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 13:38:12 INFO - no previously-included directories found matching 'docs/_build/_sources' 13:38:12 INFO - Installing collected packages: pip 13:38:12 INFO - Found existing installation: pip 1.1 13:38:12 INFO - Uninstalling pip: 13:38:12 INFO - Successfully uninstalled pip 13:38:12 INFO - Running setup.py install for pip 13:38:12 INFO - warning: no files found matching 'pip/cacert.pem' 13:38:12 INFO - warning: no files found matching '*.html' under directory 'docs' 13:38:12 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 13:38:12 INFO - no previously-included directories found matching 'docs/_build/_sources' 13:38:12 INFO - Installing pip script to /builds/slave/test/build/venv/bin 13:38:12 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 13:38:12 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 13:38:12 INFO - Successfully installed pip 13:38:12 INFO - Cleaning up... 13:38:12 INFO - Return code: 0 13:38:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:38:12 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')]} 13:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:12 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')]} 13:38:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:12 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 0x1f7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2143a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ea090>, '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 0x22ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e4f90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', '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 13:38:12 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 13:38:12 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 13:38:12 INFO - Using env: {'DISPLAY': ':0', 13:38:12 INFO - 'HOME': '/home/cltbld', 13:38:12 INFO - 'LANG': 'en_US.UTF-8', 13:38:12 INFO - 'LANGUAGE': 'en_US:en', 13:38:12 INFO - 'LOGNAME': 'cltbld', 13:38:12 INFO - 'MAIL': '/var/mail/cltbld', 13:38:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:12 INFO - 'MOZ_NO_REMOTE': '1', 13:38:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:12 INFO - 'NO_EM_RESTART': '1', 13:38:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:12 INFO - 'PWD': '/builds/slave/test', 13:38:12 INFO - 'SHELL': '/bin/bash', 13:38:12 INFO - 'SHLVL': '1', 13:38:12 INFO - 'TERM': 'linux', 13:38:12 INFO - 'TMOUT': '86400', 13:38:12 INFO - 'USER': 'cltbld', 13:38:12 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', 13:38:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:12 INFO - '_': '/tools/buildbot/bin/python'} 13:38:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:12 INFO - Downloading/unpacking psutil>=3.1.1 13:38:12 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 13:38:12 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 13:38:12 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 13:38:12 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 13:38:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:38:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:38:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:38:14 INFO - Installing collected packages: psutil 13:38:14 INFO - Running setup.py install for psutil 13:38:14 INFO - building 'psutil._psutil_linux' extension 13:38:14 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 13:38:14 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 13:38:14 INFO - building 'psutil._psutil_posix' extension 13:38:14 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 13:38:14 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 13:38:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:38:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:38:14 INFO - Successfully installed psutil 13:38:14 INFO - Cleaning up... 13:38:14 INFO - Return code: 0 13:38:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:38:14 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')]} 13:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:14 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')]} 13:38:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:14 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 0x1f7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2143a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ea090>, '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 0x22ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e4f90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', '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 13:38:14 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 13:38:14 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 13:38:14 INFO - Using env: {'DISPLAY': ':0', 13:38:14 INFO - 'HOME': '/home/cltbld', 13:38:14 INFO - 'LANG': 'en_US.UTF-8', 13:38:14 INFO - 'LANGUAGE': 'en_US:en', 13:38:14 INFO - 'LOGNAME': 'cltbld', 13:38:14 INFO - 'MAIL': '/var/mail/cltbld', 13:38:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:14 INFO - 'MOZ_NO_REMOTE': '1', 13:38:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:14 INFO - 'NO_EM_RESTART': '1', 13:38:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:14 INFO - 'PWD': '/builds/slave/test', 13:38:14 INFO - 'SHELL': '/bin/bash', 13:38:14 INFO - 'SHLVL': '1', 13:38:14 INFO - 'TERM': 'linux', 13:38:14 INFO - 'TMOUT': '86400', 13:38:14 INFO - 'USER': 'cltbld', 13:38:14 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', 13:38:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:14 INFO - '_': '/tools/buildbot/bin/python'} 13:38:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:38:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:38:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:38:16 INFO - Installing collected packages: mozsystemmonitor 13:38:16 INFO - Running setup.py install for mozsystemmonitor 13:38:16 INFO - Successfully installed mozsystemmonitor 13:38:16 INFO - Cleaning up... 13:38:17 INFO - Return code: 0 13:38:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:38:17 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')]} 13:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:17 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')]} 13:38:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:17 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 0x1f7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2143a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ea090>, '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 0x22ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e4f90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', '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 13:38:17 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 13:38:17 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 13:38:17 INFO - Using env: {'DISPLAY': ':0', 13:38:17 INFO - 'HOME': '/home/cltbld', 13:38:17 INFO - 'LANG': 'en_US.UTF-8', 13:38:17 INFO - 'LANGUAGE': 'en_US:en', 13:38:17 INFO - 'LOGNAME': 'cltbld', 13:38:17 INFO - 'MAIL': '/var/mail/cltbld', 13:38:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:17 INFO - 'MOZ_NO_REMOTE': '1', 13:38:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:17 INFO - 'NO_EM_RESTART': '1', 13:38:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:17 INFO - 'PWD': '/builds/slave/test', 13:38:17 INFO - 'SHELL': '/bin/bash', 13:38:17 INFO - 'SHLVL': '1', 13:38:17 INFO - 'TERM': 'linux', 13:38:17 INFO - 'TMOUT': '86400', 13:38:17 INFO - 'USER': 'cltbld', 13:38:17 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', 13:38:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:17 INFO - '_': '/tools/buildbot/bin/python'} 13:38:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:17 INFO - Downloading/unpacking jsonschema==2.5.1 13:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:38:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:19 INFO - Downloading functools32-3.2.3-2.tar.gz 13:38:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:38:19 INFO - warning: no files found matching '*.txt' 13:38:19 INFO - no previously-included directories found matching 'build' 13:38:19 INFO - no previously-included directories found matching 'dist' 13:38:19 INFO - no previously-included directories found matching '.git*' 13:38:19 INFO - Installing collected packages: jsonschema, functools32 13:38:19 INFO - Running setup.py install for functools32 13:38:19 INFO - warning: no files found matching '*.txt' 13:38:19 INFO - no previously-included directories found matching 'build' 13:38:19 INFO - no previously-included directories found matching 'dist' 13:38:19 INFO - no previously-included directories found matching '.git*' 13:38:19 INFO - Successfully installed jsonschema functools32 13:38:19 INFO - Cleaning up... 13:38:19 INFO - Return code: 0 13:38:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:38:19 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')]} 13:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:19 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')]} 13:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:19 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 0x1f7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2143a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ea090>, '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 0x22ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e4f90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', '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 13:38:19 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 13:38:19 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 13:38:19 INFO - Using env: {'DISPLAY': ':0', 13:38:19 INFO - 'HOME': '/home/cltbld', 13:38:19 INFO - 'LANG': 'en_US.UTF-8', 13:38:19 INFO - 'LANGUAGE': 'en_US:en', 13:38:19 INFO - 'LOGNAME': 'cltbld', 13:38:19 INFO - 'MAIL': '/var/mail/cltbld', 13:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:19 INFO - 'MOZ_NO_REMOTE': '1', 13:38:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:19 INFO - 'NO_EM_RESTART': '1', 13:38:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:19 INFO - 'PWD': '/builds/slave/test', 13:38:19 INFO - 'SHELL': '/bin/bash', 13:38:19 INFO - 'SHLVL': '1', 13:38:19 INFO - 'TERM': 'linux', 13:38:19 INFO - 'TMOUT': '86400', 13:38:19 INFO - 'USER': 'cltbld', 13:38:19 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', 13:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:19 INFO - '_': '/tools/buildbot/bin/python'} 13:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:38:19 INFO - Cleaning up... 13:38:19 INFO - Return code: 0 13:38:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:38:19 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')]} 13:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:19 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')]} 13:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:19 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 0x1f7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2143a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ea090>, '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 0x22ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e4f90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', '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 13:38:19 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 13:38:19 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 13:38:19 INFO - Using env: {'DISPLAY': ':0', 13:38:19 INFO - 'HOME': '/home/cltbld', 13:38:19 INFO - 'LANG': 'en_US.UTF-8', 13:38:19 INFO - 'LANGUAGE': 'en_US:en', 13:38:19 INFO - 'LOGNAME': 'cltbld', 13:38:19 INFO - 'MAIL': '/var/mail/cltbld', 13:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:19 INFO - 'MOZ_NO_REMOTE': '1', 13:38:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:19 INFO - 'NO_EM_RESTART': '1', 13:38:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:19 INFO - 'PWD': '/builds/slave/test', 13:38:19 INFO - 'SHELL': '/bin/bash', 13:38:19 INFO - 'SHLVL': '1', 13:38:19 INFO - 'TERM': 'linux', 13:38:19 INFO - 'TMOUT': '86400', 13:38:19 INFO - 'USER': 'cltbld', 13:38:19 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', 13:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:19 INFO - '_': '/tools/buildbot/bin/python'} 13:38:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:20 INFO - Downloading/unpacking blobuploader==1.2.4 13:38:20 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 13:38:20 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 13:38:20 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 13:38:20 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 13:38:21 INFO - Downloading blobuploader-1.2.4.tar.gz 13:38:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:38:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:38:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:21 INFO - Downloading docopt-0.6.1.tar.gz 13:38:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:38:21 INFO - Installing collected packages: blobuploader, requests, docopt 13:38:21 INFO - Running setup.py install for blobuploader 13:38:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:38:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:38:21 INFO - Running setup.py install for requests 13:38:22 INFO - Running setup.py install for docopt 13:38:22 INFO - Successfully installed blobuploader requests docopt 13:38:22 INFO - Cleaning up... 13:38:22 INFO - Return code: 0 13:38:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:38:22 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')]} 13:38:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:22 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')]} 13:38:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:22 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 0x1f7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2143a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ea090>, '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 0x22ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e4f90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', '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 13:38:22 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 13:38:22 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 13:38:22 INFO - Using env: {'DISPLAY': ':0', 13:38:22 INFO - 'HOME': '/home/cltbld', 13:38:22 INFO - 'LANG': 'en_US.UTF-8', 13:38:22 INFO - 'LANGUAGE': 'en_US:en', 13:38:22 INFO - 'LOGNAME': 'cltbld', 13:38:22 INFO - 'MAIL': '/var/mail/cltbld', 13:38:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:22 INFO - 'MOZ_NO_REMOTE': '1', 13:38:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:22 INFO - 'NO_EM_RESTART': '1', 13:38:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:22 INFO - 'PWD': '/builds/slave/test', 13:38:22 INFO - 'SHELL': '/bin/bash', 13:38:22 INFO - 'SHLVL': '1', 13:38:22 INFO - 'TERM': 'linux', 13:38:22 INFO - 'TMOUT': '86400', 13:38:22 INFO - 'USER': 'cltbld', 13:38:22 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', 13:38:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:22 INFO - '_': '/tools/buildbot/bin/python'} 13:38:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:38:22 INFO - Running setup.py (path:/tmp/pip-Cn7tes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:38:22 INFO - Running setup.py (path:/tmp/pip-RbFONP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:38:22 INFO - Running setup.py (path:/tmp/pip-_0gI7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:38:22 INFO - Running setup.py (path:/tmp/pip-oLiJbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:38:22 INFO - Running setup.py (path:/tmp/pip-whn5Vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:38:22 INFO - Running setup.py (path:/tmp/pip-dj_qpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:38:22 INFO - Running setup.py (path:/tmp/pip-yWCPjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:38:23 INFO - Running setup.py (path:/tmp/pip-V61rBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:38:23 INFO - Running setup.py (path:/tmp/pip-Zefide-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:38:23 INFO - Running setup.py (path:/tmp/pip-pQeph7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:38:23 INFO - Running setup.py (path:/tmp/pip-9FmDP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:38:23 INFO - Running setup.py (path:/tmp/pip-pYlez8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:38:23 INFO - Running setup.py (path:/tmp/pip-gy1p2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:38:23 INFO - Running setup.py (path:/tmp/pip-hGYJhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:38:23 INFO - Running setup.py (path:/tmp/pip-zXvKh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:38:23 INFO - Running setup.py (path:/tmp/pip-jW8HOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:38:23 INFO - Running setup.py (path:/tmp/pip-qRdVdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:38:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:38:23 INFO - Running setup.py install for manifestparser 13:38:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:38:23 INFO - Running setup.py install for mozcrash 13:38:23 INFO - Running setup.py install for mozdebug 13:38:24 INFO - Running setup.py install for mozdevice 13:38:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:38:24 INFO - Running setup.py install for mozfile 13:38:24 INFO - Running setup.py install for mozhttpd 13:38:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:38:24 INFO - Running setup.py install for mozinfo 13:38:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:38:24 INFO - Running setup.py install for mozInstall 13:38:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:38:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:38:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:38:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:38:24 INFO - Running setup.py install for mozleak 13:38:24 INFO - Running setup.py install for mozlog 13:38:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:38:24 INFO - Running setup.py install for moznetwork 13:38:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:38:24 INFO - Running setup.py install for mozprocess 13:38:24 INFO - Running setup.py install for mozprofile 13:38:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:38:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:38:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:38:25 INFO - Running setup.py install for mozrunner 13:38:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:38:25 INFO - Running setup.py install for mozscreenshot 13:38:25 INFO - Running setup.py install for moztest 13:38:25 INFO - Running setup.py install for mozversion 13:38:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:38:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:38:25 INFO - Cleaning up... 13:38:25 INFO - Return code: 0 13:38:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:38:25 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')]} 13:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:25 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')]} 13:38:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:25 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 0x1f7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2143a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ea090>, '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 0x22ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e4f90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', '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 13:38:25 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 13:38:25 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 13:38:25 INFO - Using env: {'DISPLAY': ':0', 13:38:25 INFO - 'HOME': '/home/cltbld', 13:38:25 INFO - 'LANG': 'en_US.UTF-8', 13:38:25 INFO - 'LANGUAGE': 'en_US:en', 13:38:25 INFO - 'LOGNAME': 'cltbld', 13:38:25 INFO - 'MAIL': '/var/mail/cltbld', 13:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:25 INFO - 'MOZ_NO_REMOTE': '1', 13:38:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:25 INFO - 'NO_EM_RESTART': '1', 13:38:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:25 INFO - 'PWD': '/builds/slave/test', 13:38:25 INFO - 'SHELL': '/bin/bash', 13:38:25 INFO - 'SHLVL': '1', 13:38:25 INFO - 'TERM': 'linux', 13:38:25 INFO - 'TMOUT': '86400', 13:38:25 INFO - 'USER': 'cltbld', 13:38:25 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', 13:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:25 INFO - '_': '/tools/buildbot/bin/python'} 13:38:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:38:25 INFO - Running setup.py (path:/tmp/pip-CLo8vH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:38:25 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)) 13:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:38:25 INFO - Running setup.py (path:/tmp/pip-XePgS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:38:25 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)) 13:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:38:25 INFO - Running setup.py (path:/tmp/pip-gW2M2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:38:25 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)) 13:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:38:25 INFO - Running setup.py (path:/tmp/pip-9jLSuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:38:25 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)) 13:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:38:25 INFO - Running setup.py (path:/tmp/pip-uqoGA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:38:26 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)) 13:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:38:26 INFO - Running setup.py (path:/tmp/pip-9Q9whD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:38:26 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)) 13:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:38:26 INFO - Running setup.py (path:/tmp/pip-oCZlz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:38:26 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)) 13:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:38:26 INFO - Running setup.py (path:/tmp/pip-VxOzxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:38:26 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)) 13:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:38:26 INFO - Running setup.py (path:/tmp/pip-KiBJVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:38:26 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)) 13:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:38:26 INFO - Running setup.py (path:/tmp/pip-ybQpxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:38:26 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)) 13:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:38:26 INFO - Running setup.py (path:/tmp/pip-jG7POk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:38:26 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)) 13:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:38:26 INFO - Running setup.py (path:/tmp/pip-EMUr3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:38:26 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)) 13:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:38:26 INFO - Running setup.py (path:/tmp/pip-nvPirz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:38:26 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)) 13:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:38:26 INFO - Running setup.py (path:/tmp/pip-7pyADY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:38:26 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)) 13:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:38:26 INFO - Running setup.py (path:/tmp/pip-4qY_It-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:38:26 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)) 13:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:38:26 INFO - Running setup.py (path:/tmp/pip-FEOPqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:38:26 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)) 13:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:38:26 INFO - Running setup.py (path:/tmp/pip-ajgVzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:38:26 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)) 13:38:26 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)) 13:38:26 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)) 13:38:26 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)) 13:38:26 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)) 13:38:26 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)) 13:38:26 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)) 13:38:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:38:26 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 13:38:26 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 13:38:26 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 13:38:26 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 13:38:28 INFO - Downloading blessings-1.6.tar.gz 13:38:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:38:28 INFO - Installing collected packages: blessings 13:38:28 INFO - Running setup.py install for blessings 13:38:28 INFO - Successfully installed blessings 13:38:28 INFO - Cleaning up... 13:38:28 INFO - Return code: 0 13:38:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:38:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:38:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:38:28 INFO - Reading from file tmpfile_stdout 13:38:28 INFO - Current package versions: 13:38:28 INFO - argparse == 1.2.1 13:38:28 INFO - blessings == 1.6 13:38:28 INFO - blobuploader == 1.2.4 13:38:28 INFO - docopt == 0.6.1 13:38:28 INFO - functools32 == 3.2.3-2 13:38:28 INFO - jsonschema == 2.5.1 13:38:28 INFO - manifestparser == 1.1 13:38:28 INFO - mozInstall == 1.13 13:38:28 INFO - mozcrash == 1.0 13:38:28 INFO - mozdebug == 0.1 13:38:28 INFO - mozdevice == 0.51 13:38:28 INFO - mozfile == 1.2 13:38:28 INFO - mozhttpd == 0.7 13:38:28 INFO - mozinfo == 0.10 13:38:28 INFO - mozleak == 0.1 13:38:28 INFO - mozlog == 3.5 13:38:28 INFO - moznetwork == 0.27 13:38:28 INFO - mozprocess == 0.25 13:38:28 INFO - mozprofile == 0.28 13:38:28 INFO - mozrunner == 6.13 13:38:28 INFO - mozscreenshot == 0.1 13:38:28 INFO - mozsystemmonitor == 0.3 13:38:28 INFO - moztest == 0.8 13:38:28 INFO - mozversion == 1.4 13:38:28 INFO - psutil == 3.1.1 13:38:28 INFO - requests == 1.2.3 13:38:28 INFO - wsgiref == 0.1.2 13:38:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:38:28 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')]} 13:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:28 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')]} 13:38:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:28 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 0x1f7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2143a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ea090>, '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 0x22ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e4f90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', '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 13:38:28 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 13:38:28 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 13:38:28 INFO - Using env: {'DISPLAY': ':0', 13:38:28 INFO - 'HOME': '/home/cltbld', 13:38:28 INFO - 'LANG': 'en_US.UTF-8', 13:38:28 INFO - 'LANGUAGE': 'en_US:en', 13:38:28 INFO - 'LOGNAME': 'cltbld', 13:38:28 INFO - 'MAIL': '/var/mail/cltbld', 13:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:28 INFO - 'MOZ_NO_REMOTE': '1', 13:38:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:28 INFO - 'NO_EM_RESTART': '1', 13:38:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:28 INFO - 'PWD': '/builds/slave/test', 13:38:28 INFO - 'SHELL': '/bin/bash', 13:38:28 INFO - 'SHLVL': '1', 13:38:28 INFO - 'TERM': 'linux', 13:38:28 INFO - 'TMOUT': '86400', 13:38:28 INFO - 'USER': 'cltbld', 13:38:28 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', 13:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:28 INFO - '_': '/tools/buildbot/bin/python'} 13:38:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:28 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)) 13:38:28 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)) 13:38:28 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)) 13:38:28 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)) 13:38:28 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)) 13:38:28 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)) 13:38:28 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)) 13:38:28 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)) 13:38:28 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)) 13:38:28 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 13:38:28 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 13:38:28 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 13:38:28 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 13:38:28 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 13:38:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 13:38:29 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)) 13:38:29 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)) 13:38:29 INFO - Installing collected packages: simplejson 13:38:29 INFO - Running setup.py install for simplejson 13:38:29 INFO - building 'simplejson._speedups' extension 13:38:29 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 13:38:30 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 13:38:30 INFO - Successfully installed simplejson 13:38:30 INFO - Cleaning up... 13:38:30 INFO - Return code: 0 13:38:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:38:30 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')]} 13:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:30 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')]} 13:38:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:30 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 0x1f7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2143a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ea090>, '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 0x22ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e4f90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', '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 13:38:30 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 13:38:30 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 13:38:30 INFO - Using env: {'DISPLAY': ':0', 13:38:30 INFO - 'HOME': '/home/cltbld', 13:38:30 INFO - 'LANG': 'en_US.UTF-8', 13:38:30 INFO - 'LANGUAGE': 'en_US:en', 13:38:30 INFO - 'LOGNAME': 'cltbld', 13:38:30 INFO - 'MAIL': '/var/mail/cltbld', 13:38:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:30 INFO - 'MOZ_NO_REMOTE': '1', 13:38:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:30 INFO - 'NO_EM_RESTART': '1', 13:38:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:30 INFO - 'PWD': '/builds/slave/test', 13:38:30 INFO - 'SHELL': '/bin/bash', 13:38:30 INFO - 'SHLVL': '1', 13:38:30 INFO - 'TERM': 'linux', 13:38:30 INFO - 'TMOUT': '86400', 13:38:30 INFO - 'USER': 'cltbld', 13:38:30 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', 13:38:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:30 INFO - '_': '/tools/buildbot/bin/python'} 13:38:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:38:31 INFO - Cleaning up... 13:38:31 INFO - Return code: 0 13:38:31 INFO - Running post-action listener: _resource_record_post_action 13:38:31 INFO - Running post-action listener: _start_resource_monitoring 13:38:31 INFO - Starting resource monitoring. 13:38:31 INFO - [mozharness: 2017-09-15 20:38:31.192152Z] Finished create-virtualenv step (success) 13:38:31 INFO - [mozharness: 2017-09-15 20:38:31.192748Z] Running install step. 13:38:31 INFO - Running pre-action listener: _resource_record_pre_action 13:38:31 INFO - Running main action method: install 13:38:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:38:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:38:31 INFO - Reading from file tmpfile_stdout 13:38:31 INFO - Detecting whether we're running mozinstall >=1.0... 13:38:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:38:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:38:31 INFO - Reading from file tmpfile_stdout 13:38:31 INFO - Output received: 13:38:31 INFO - Usage: mozinstall [options] installer 13:38:31 INFO - Options: 13:38:31 INFO - -h, --help show this help message and exit 13:38:31 INFO - -d DEST, --destination=DEST 13:38:31 INFO - Directory to install application into. [default: 13:38:31 INFO - "/builds/slave/test"] 13:38:31 INFO - --app=APP Application being installed. [default: firefox] 13:38:31 INFO - mkdir: /builds/slave/test/build/application 13:38:31 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'] 13:38:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 13:38:49 INFO - Reading from file tmpfile_stdout 13:38:49 INFO - Output received: 13:38:49 INFO - /builds/slave/test/build/application/firefox/firefox 13:38:49 INFO - Running post-action listener: _resource_record_post_action 13:38:49 INFO - [mozharness: 2017-09-15 20:38:49.123773Z] Finished install step (success) 13:38:49 INFO - [mozharness: 2017-09-15 20:38:49.124111Z] Running setup-mitmproxy step. 13:38:49 INFO - Running pre-action listener: _resource_record_pre_action 13:38:49 INFO - Running main action method: setup_mitmproxy 13:38:49 INFO - Skipping: mitmproxy is not required 13:38:49 INFO - Running post-action listener: _resource_record_post_action 13:38:49 INFO - [mozharness: 2017-09-15 20:38:49.125784Z] Finished setup-mitmproxy step (success) 13:38:49 INFO - [mozharness: 2017-09-15 20:38:49.126093Z] Running run-tests step. 13:38:49 INFO - Running pre-action listener: _resource_record_pre_action 13:38:49 INFO - Running pre-action listener: _set_gcov_prefix 13:38:49 INFO - Running main action method: run_tests 13:38:49 WARNING - Try message not found. 13:38:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:38:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:38:49 INFO - Python 2.7.3 13:38:49 INFO - Return code: 0 13:38:49 INFO - grabbing minidump binary from tooltool 13:38: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')]} 13:38:49 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 0x22ea090>, '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 0x22ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e4f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:38:49 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 13:38:49 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 13:38:49 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 13:38:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:38:49 INFO - Return code: 0 13:38:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:38:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:38:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:38:49 INFO - ENV: RUST_BACKTRACE is now full 13:38:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:38:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:38:49 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/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 13:38:49 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/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 13:38:49 INFO - Using env: {'DISPLAY': ':0', 13:38:49 INFO - 'HOME': '/home/cltbld', 13:38:49 INFO - 'LANG': 'en_US.UTF-8', 13:38:49 INFO - 'LANGUAGE': 'en_US:en', 13:38:49 INFO - 'LOGNAME': 'cltbld', 13:38:49 INFO - 'MAIL': '/var/mail/cltbld', 13:38:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:38:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:38:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:49 INFO - 'MOZ_NO_REMOTE': '1', 13:38:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:38:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:49 INFO - 'NO_EM_RESTART': '1', 13:38:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:49 INFO - 'PWD': '/builds/slave/test', 13:38:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:38:49 INFO - 'RUST_BACKTRACE': 'full', 13:38:49 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:38:49 INFO - 'SHELL': '/bin/bash', 13:38:49 INFO - 'SHLVL': '1', 13:38:49 INFO - 'TERM': 'linux', 13:38:49 INFO - 'TMOUT': '86400', 13:38:49 INFO - 'USER': 'cltbld', 13:38:49 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', 13:38:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:49 INFO - '_': '/tools/buildbot/bin/python'} 13:38:49 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/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 13:38:49 INFO - mozversion application_buildid: 20170915192121 13:38:49 INFO - mozversion application_changeset: 5b39f8dcc5cd6a38fe99564a9cfc90eedfad1639 13:38:49 INFO - mozversion application_display_name: Nightly 13:38:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:38:49 INFO - mozversion application_name: Firefox 13:38:49 INFO - mozversion application_remotingname: firefox 13:38:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:38:49 INFO - mozversion application_vendor: Mozilla 13:38:49 INFO - mozversion application_version: 57.0a1 13:38:49 INFO - mozversion platform_buildid: 20170915192121 13:38:49 INFO - mozversion platform_changeset: 5b39f8dcc5cd6a38fe99564a9cfc90eedfad1639 13:38:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:38:49 INFO - mozversion platform_version: 57.0a1 13:38:49 INFO - using testdate: 1505507929 13:38:49 INFO - actual date: 1505507929 13:38:49 INFO - starting webserver on 'localhost:49156' 13:38:49 INFO - SUITE-START | Running 2 tests 13:38:49 INFO - TEST-START | dromaeo_css 13:38:49 INFO - Initialising browser for dromaeo_css test... 13:38:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49156/getInfo.html -profile /tmp/tmpenLqB2/profile 13:38:49 INFO - TEST-INFO | started process 10985 (/builds/slave/test/build/application/firefox/firefox http://localhost:49156/getInfo.html) 13:38:54 INFO - TEST-INFO | 10985: exit 0 13:38:55 INFO - Browser initialized. 13:38:55 INFO - Running cycle 1/1 for dromaeo_css test... 13:38:55 INFO - Using env: {'DISPLAY': ':0', 13:38:55 INFO - 'HOME': '/home/cltbld', 13:38:55 INFO - 'JSGC_DISABLE_POISONING': '1', 13:38:55 INFO - 'LANG': 'en_US.UTF-8', 13:38:55 INFO - 'LANGUAGE': 'en_US:en', 13:38:55 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 13:38:55 INFO - 'LOGNAME': 'cltbld', 13:38:55 INFO - 'MAIL': '/var/mail/cltbld', 13:38:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:38:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:38:55 INFO - 'MOZ_CRASHREPORTER': '1', 13:38:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:55 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 13:38:55 INFO - 'MOZ_NO_REMOTE': '1', 13:38:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:38:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:55 INFO - 'NO_EM_RESTART': '1', 13:38:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:55 INFO - 'PWD': '/builds/slave/test', 13:38:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:38:55 INFO - 'RUST_BACKTRACE': 'full', 13:38:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:38:55 INFO - 'SHELL': '/bin/bash', 13:38:55 INFO - 'SHLVL': '1', 13:38:55 INFO - 'TERM': 'linux', 13:38:55 INFO - 'TMOUT': '86400', 13:38:55 INFO - 'USER': 'cltbld', 13:38:55 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', 13:38:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:55 INFO - '_': '/tools/buildbot/bin/python'} 13:38:55 INFO - TEST-INFO | started process 11216 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpenLqB2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 13:38:55 INFO - PID 11216 | 13:38:55 INFO - PID 11216 | (/builds/slave/test/build/application/firefox/firefox:11266): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 13:38:55 INFO - PID 11216 | 13:39:06 INFO - PID 11216 | RSS: Main: 167092224 13:39:06 INFO - PID 11216 | 13:42:02 INFO - PID 11216 | Cycle 1(1): loaded http://localhost:49156/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49156/tests/dromaeo/cssquery-ext.html) 13:42:04 INFO - PID 11216 | RSS: Main: 169295872 13:42:04 INFO - PID 11216 | 13:45:06 INFO - PID 11216 | Cycle 1(1): loaded http://localhost:49156/tests/dromaeo/cssquery-ext.html (next: http://localhost:49156/tests/dromaeo/cssquery-jquery.html) 13:45:06 INFO - PID 11216 | RSS: Main: 165498880 13:45:06 INFO - PID 11216 | 13:48:08 INFO - PID 11216 | Cycle 1(1): loaded http://localhost:49156/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49156/tests/dromaeo/cssquery-mootools.html) 13:48:08 INFO - PID 11216 | RSS: Main: 168382464 13:48:08 INFO - PID 11216 | 13:51:10 INFO - PID 11216 | Cycle 1(1): loaded http://localhost:49156/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49156/tests/dromaeo/cssquery-prototype.html) 13:51:11 INFO - PID 11216 | RSS: Main: 165142528 13:51:11 INFO - PID 11216 | 13:54:14 INFO - PID 11216 | Cycle 1(1): loaded http://localhost:49156/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49156/tests/dromaeo/cssquery-yui.html) 13:54:14 INFO - PID 11216 | RSS: Main: 168476672 13:54:14 INFO - PID 11216 | 13:57:16 INFO - PID 11216 | Cycle 1(1): loaded http://localhost:49156/tests/dromaeo/cssquery-yui.html (next: http://localhost:49156/tests/dromaeo/cssquery-dojo.html) 13:57:16 INFO - PID 11216 | RSS: Main: 171261952 13:57:16 INFO - PID 11216 | 13:57:16 INFO - PID 11216 | __start_tp_report 13:57:16 INFO - PID 11216 | _x_x_mozilla_page_load 13:57:16 INFO - PID 11216 | _x_x_mozilla_page_load_details 13:57:16 INFO - PID 11216 | |i|pagename|runs| 13:57:16 INFO - PID 11216 | |0;dojo.html;3725.366687663097;3742.2889650966313;3752.81235938203;3762.680172185364;3765.566959799624;6004.789832355868;6025.427584379488;6034.306054803699;6052.515798736102;6097.268327800664;6080.331163572004;6126.663033533165;6160.938390616093;6161;6171.166892469515;5430.864228394302;5505.9724701376435;5508.862278443031;5510.641808282468;5566.276384070065;388.9045973651296;5514.91727624086;5569.415211402785;5571.749271282782;5584.050711379075;5457.5633949283965;5465.672059676433;5471.917921231185;5545.944540554624;5557.6109672322955;5453.345598528163;5470.6444081135;5524.475174858382;5532.834014979557;5535.889282214373;5415.296011518478;5524.8342549723575;5529.751161197756;5542.0855558832745;5548.528375088123;5970.880582388371;5982.192404025444;6106.725197366085;6119.204503414572;6129.325774164839;6451.29035806061;6519.641419721914;6546.509011824132;6601.6699165041555;6619.304972977816;6531.412172904462;6623.403893649546;6641.402273795334;6643.966780166116;6653.634050127241;6089.665068421235;6162.198914141133;6173.999999999955;6204.6587437690905;6229.065640154013;6672.366125218096;6707.865842683216;6787.389134977876;6790.117284752951;6829.590224586541;6472.158619379545;6480.060391243243;6532.673366331759;6555.704993275314;6574.8356291093105;6494.6103233806125;6590.406863382319;6614.7023383947835;6662.56693314933;6663.433608143265;6488.772892948724;6494.805155845333;6513.088167656532;6516.706748196342;6572.178477690289;6555.868882622321;6567.2447668518735;6585.440237579859;6599.8020059398295;6644.966775166094;4773.021530586221;4774.928376074362;4834.975825120852;4840.104580652591;4845.3216549683075;4379.5401482843845;4388.034632380871;4409.823607055746;4422.624076953431;4432.224360736858;5845.883082338414;5902.970485147633;5922.318933322636;5971.850703732354;6014.338422773491;6631.071649969008;6643.435307998778;6656.234533028667;6662.666866656745;6671.966640166768;5944.435278648608;5951.404859514014;5970.044792833126;6031.306399764083;6039.758409663652;5322.54758345545;5327.067763141435;5350.197470379397;5401.081816091274;5415.783368665209;6644.933550664431;6683.732650694015;6702.229243637044;6739.393454589111;6749.358810912955;6621.404073633396;6663.13379260693;6663.833404165031;6688.431483323972;6705.463562914858;6714.395704386433;6715.261321254756;6723.798286051427;6733.124693789776;6828.556143850634;6477.384648458389;6511.055896895018;6549.443297319591;6569.507286953402;6613.2394774601535;6640.9335906642245;6650.600963942179;6650.76722314736;6654.667266636746;6659.7003134860015;6294.244690637152;6365.299817020032;6379.585326953733;6426.29310775805;6454.128692626436;6307.05394190875;6310.400511951358;6352.333005034405;6400.647964362005;6400.839979000562;6309.589876658002;6310.368963103653;6386.872262554816;6391.169148010915;6411.775587854403;6342.016987366966;6388.8083357499345;6432.6462044588;6468.967655161694;6496.870062598627;6181.258249009968;6212.844678883064;6215.378462153929;6221.2845522765465;6257.843553911189 13:57:16 INFO - PID 11216 | |1;ext.html;962.0523784072687;977.8288799460092;992.9702108936734;994.5673631970094;996.432033740768;44339.77830110849;45378;45541;45639.31541026883;45693.54306456934;41758.79120604401;41764.16471670572;42054.57945420552;42085.78957105218;42087;7442.032535770356;7548.849023019537;7561.886571701429;7598.4301177411635;7598.658060387296;7311.341979221869;7374.483786134972;7377.446691498132;7390.26097390262;7405.333519983201;7463.029806125196;8051.315638170762;8280.627371768285;8302.418830681854;8305.750827475185;8150.184981501861;8157.347412207039;8195.549244791504;8201.876971845368;8219.630116644736;7848.882266766003;8072.273495385443;8076.152004039609;8079.878801817978;8080.676772929076;40989.590104099174;41230.58769412328;41620;41689;41703.58296417027;3767.981160094182;3801.904952376213;3814.5041144651295;3819.3507103792144;3820.2359528094494;33661;33797.83101084479;33822.49266261008;33980.49029264563;34016.31967360664;5498.202760599731;5502.438829959492;5509.063459211903;5528.419515950807;5575.972120139373;222205;222244;222580;223270;223484;46160.76919615448;46994.29508557374;51193.7440312796;51213;51777.7411112942;54246;54252;54319.18521222185;54390.45609543933;54435.45564544383;15481.922590386976;15488.303026363841;15571.065736055873;15614.531564053097;15616.141112238709;12299.877001230052;12324.876751232552;12386.256824590553;12474.251544907152;12478.313692746804;14716.411343546139;14797.112173269423;14798.186099764402;14836.777448338284;14850;5812.651240925558;5840.532757379399;5879.823605291848;5952.642841429528;5969.671668058211;1097.91765617579;1120.2270433400927;1125.8930401611833;1133.0312582741801;1139.1342579639534;25510.617340739904;25985.22044338673;26019.609705854426;26022.479550408887;26179.214623561325;1206.6741979665442;1219.9329036903055;1222.9021678265717;1236.5610208376077;1246.975060498803;13532.25572593497;13602.31988400576;13632.522861699794;13709.725805483835;13856.237906915216;269536;269826;269870;269927;273265;60014.399856000884;60127;60182.69908650516;60475;60589;1525.2221367102636;1544.4440001599414;1566.216891554223;1571.2615070916652;1588.126530408294;25132.693872863776;25174.49651007006;25314.620280696174;25383.36541586475;25470.235892923243;41886.371704425066;42172.57827421687;42233.57766422419;42261.78869105635;42330.36504452497;225596;228506;246292;248861;249218;34777.30445391028;35016.299674006885;35166.472502911965;38386.42420363753;42314.57685423106;53555.46444535661;53705;53726.462735372144;53742.19386709281;53916.19125713039;38827.80586097051;38924.22151556896;39132.41301380539;39351.2129757409;39536.40695389629;38869.61130388773;38978;39086.80456597813;39197.8040109809;39223.80388098041;46112.7694361526;46363;46373;46695;46904.765476172404;37803.62196378001;37958.62041379551;38378;38433.807830960664;38539;27034.864825675744;27088.1873543794;27284.863575681993;27486.312842179104;28218.435631286848 13:57:16 INFO - PID 11216 | |2;jquery.html;584.374719050616;591.1546488521412;594.2275042444822;598.5480961873787;603.6076550242342;23743.643845342332;24388.02447902084;24533.75466245337;24564.877175614143;24570;23232.535349293004;24287.635685464687;24777.380565485873;24846.50306993859;24870.502589948235;6606.933930660692;6789.3889549940495;6797.0144329072255;6798.626075565842;6812.863742725142;6521.3478652134645;6673.666316684171;6681.365270299314;6798.52410331277;6830.419414349786;6774.356436138559;6806.931930680679;6809.216940051883;6809.965950170243;6811.625360605165;6793.354631310018;6794.286599907013;6796.592204467748;6801.93198068018;6811.863762724743;6072.2713274406915;6277.748890044393;6279.309275979638;6286.591371560834;6301.810945671591;29920.551191732142;30225.848870755726;30266.092017239516;30511.23721906948;30539.847300763577;2282.6119559674894;2284.4288927768057;2291.724993000834;2311.6879221305244;2312.7224733032144;20234.49413764653;20804.583908321747;20863.89568052165;21079.367618971457;21127;2453.570625140593;2483.379155211197;2501.837380570257;2517.546841568517;2537.0612873236837;129139;135379;142559;150381.9999999989;151504;15073.786368991849;15397.461088861835;15615.3753849847;15683.215839207995;16218.75671864923;24529;24646.137385191432;24727.5054498909;24729.87635061849;24757.876210618833;9441.386309889835;9449.90550094504;9582.520873956275;9705.951470242604;9756.609735610638;8415.242628163494;8488.74533763988;8491.320694344562;8512.70205542803;8584.74245772628;9453.196478299284;9587.472689002172;9597.280203984728;9657.22742180624;10000.999900009941;2784.7215278472395;2798;2856.714328567168;2880.8271503709843;2881.5533592293227;1029.4955471818755;1044.6813721814767;1047.5338474378827;1061.7292590389557;1065.6376831877199;20658.690119648218;20786.792132078786;21153.471163221042;21244.787552124282;21475.140994360365;1117.776444711061;1123.8538989931258;1135.4379582106744;1140.049727693546;1146.1919346860443;11329.603463878724;11630.302181869114;11783.234089784137;11840.348780817138;11930.880691192977;560350;580475;581581;582077;600354;130941;131390;132964.3351783235;133251;134070;1104.939228342433;1155.070168514354;1224.7305592769576;1235.110720281396;1290.6257185416125;22111.557768844854;22275.66586501236;22419.215327463462;22452.550948981254;22497.550048999256;65747;65822.67088664525;66764.6661766688;66795.33204667892;69149.65425172843;86789;87188;87460;87973.56013219892;88523;21227.257046003935;21840.672389913845;21889;22085;22310.665340019586;24661.87669061643;24816.131435399675;25098.74901250964;25106;25170.74829251684;20100.59798804045;20200.59598808045;20314.187432502535;20691.896540517202;20712.378628641163;17657.205425755616;17962.91018544899;18064.36774712879;18144.45566633003;18412.35556755507;20305.79694203039;20430.591388172452;20536.897315513328;20695.68956465624;20854.061567229208;21412.072913437867;21557.353279401643;21617.459563511038;21726.67409988883;21759.238426654993;19674.213031479154;19727.210911563376;19756.802431975495;20088.296909608092;20157.697634535787 13:57:16 INFO - PID 11216 | |3;mootools.html;1478.652516658585;1536.2088524409928;1538.7076455473452;1541.1446647110856;1545.783590297358;15996.280167392466;17123.914380428098;17141.228644710987;17155.228014739336;17156.914215428937;16896.746548801748;17000;17040.829591704078;17059.232334544944;17089.91455042726;2862.2844288927768;2879.4385094906447;2919.284775230061;2922.8246305221755;2931.794774365795;2834.8015638905285;2871.3970066286106;2874.3101655602713;2877.165621969637;2878.481873262812;2682.798790090741;2891.5807207954836;2903.5354343305075;2904.288449329917;2904.665963414203;2748.6014527893544;2863.4988876946554;2886.393857289961;2887.2781804548863;2910.592517047615;2852.7717782577342;2873.454043731684;2874.3820078682975;2876.2090425133047;2880.3807181456086;16344.918275408547;16364.099974501398;16410.507684769476;16420;16436.506904792874;8217.424780265384;8552.5296108714;8579;8579.442336248125;8585.699500517514;5522.420145884705;5773.769049238025;5858.502027327682;5874.295084589834;5943.821685349447;2083.3541602103396;2119.4171602809197;2142.3787101740477;2163.5456554123493;2178.880161591112;28881.855590721912;29493.999999999785;29504.26239344012;29547.409051819057;29558;7032.226455089936;7245.637718114073;7282.344588987016;7439;7458.105027396748;18819.529511762317;18922.52693682669;18947.526311842037;18950.81049189518;19031.61936761257;7922;8028.237317454832;8031.955845740016;8032;8062.717804876801;7192.748253811092;7263.927360726325;7818.14000459949;7859.331956783623;8018.959905200553;5606.887862242732;5609.551235901118;5708.20085188074;5763.106718458645;5937.435943585352;1604.1337677653937;1623.6590316033528;1636.075617276235;1655.6605895791333;1664.192866459766;872.4024043530202;905.6105874473908;906.1753804038293;906.410832958569;912.9409884533909;7335.88996165058;7358.889616655754;7406.518576292524;7407.962960185272;7408.296211859864;771.6026246483066;799.5873097755965;802.6949759091508;814.4054839966857;822.1490757066407;5456.645318054393;5473.808416705396;5598.664080155204;5827.184194212814;5918.911216331759;23886.402839929142;24132.758672413052;24394.634080488806;25078.37304067413;25169.24492265235;23785.405364866016;24014.87992560026;24768.5046299073;24801.25596232116;25147.87426062858;12121;12184.20802647825;12309.69225769345;12380;12439.253644781342;9113.635454581743;9117.90882091197;9137.543122843697;9274.072592740671;9348.53257337144;20476;20502;20795.37613871526;21076.789232107483;21229.256976005767;21790.782092178877;21831.345059648236;21911;21947.780522194575;22093.116275348806;12094.637160885188;12110.878891210976;12264.632061038183;12283.017358611469;12418.81371779442;15368.154751488775;15369.385224590891;15648.295826688054;15663.138527381103;15688.92155539215;12455.813162802384;12867.099303048697;12873.613791586267;12893.226406415644;12932.482700691868;12146.210496317712;12382.56661016896;12727.681807954876;12734.68163295925;12747.872521274669;10568.365898046142;10631;10679.946600266949;10684.83972740425;10697.090747286567;7711.460197786101;7718.382529397522;7819.569923654026;7831.412644051833;7894.0132483439575;8251.2986396157;8336.958315208385;8388.916110838814;8395.538245396561;8434.072252052392 13:57:16 INFO - PID 11216 | |4;prototype.html;498.31481712645115;522.7464679630381;536.6753114365808;541.4720647368816;550.1060776238612;6154;6189.53578481614;6281.309056003836;6375.3305902880165;6379.07503412005;6180.381961803829;6273.968630156843;6278.304857688421;6285.528585356105;6312.33720459352;4859;5119.104156772569;5133.230015497668;5141.280220769096;5151.227315902626;4903.19097348937;4944.220080770938;4958.611588755154;4981.626378021645;5013.974930125344;4945.159322915095;4957.702537847723;4992.350994370745;5003.099442100421;5007.4491805901325;4887.853364399074;4937.086638971784;4945.307656928033;4983.526564976321;4993.650444468889;4493.415855938707;4523.344115103291;4523.864284071483;4548.476260452752;4560.589546940758;6214.906776398358;6278.372162783685;6286.842828929268;6291.779787707408;6318.147050148263;5809.841567938;5820.912686309709;5843.5033022193165;5849;5851.795187168471;6654.900176497356;6672.499562532781;6684.732610695566;6700;6705.463562915004;5032.7418145463635;5045.91281062337;5053.393592768891;5054.545090941796;5062.265971434158;6923.507537286573;6946.44428445718;6949.5830250184645;7004.81281017136;7020.29797020299;5112.156494178419;5124.948750512522;5146.724607277571;5165.199394093921;5193.37679478465;5222.00781851447;5244.213367994755;5257.263983042377;5262.500062494057;5267.578593712494;5721.113227449752;5765.913511297334;5791.913121303184;5838.9124163137585;5840.2115714378015;6301.306856245809;6489.929161688363;6589.472842172615;6611.537192396486;6694.364035416627;1726.611512409723;1797.4248240563156;1798.7032139697064;1807.2228941555256;1817.9272829086954;1244.3280628460711;1246.2211118051218;1253.3733133433284;1260.684828792802;1282.1537785061814;5942.37605051472;6001.559625689803;6084.178635884187;6086.421789929949;6118.20463339763;6711.6979735912;6758.357955994173;6774.898376524356;6790.354916282945;6813.863722725518;6059.666718330534;6063.878722425527;6082.684936709504;6106.862598090633;6130.877382452326;3524.33037722837;3614.855405783792;3616.3309787689363;3621.80880567253;3626.6917312028245;6899.275576064541;6953;6954.65226738661;7010.544314619533;7017.263187365355;6921.7231310747;6982.825429364307;7009.228984811667;7087.397571206505;7107.92892071083;6904.102466679299;6939.93060069403;6960.651967401508;6993.580385176906;7008.318003678978;6579.795768417634;6666.566673166229;6678.632675202716;6699.631520266432;6814.250432452525;6595.472362210916;6601.50488713358;6625.972974189009;6690;6698.899516507354;6703.865922681422;6803.863922721617;6874.415674667709;6900.6204658744255;6960.512764106464;5073.365829271341;5145.434002259673;5156.303898973741;5179.274901513716;5183.481651834786;6494.512911531559;6743.9325606743305;6816.829579260559;6860.485463590151;6866.631012300748;6309.684515774285;6545.574537655038;6556;6565.44193743518;6581.3747693969;6506.73973041092;6507.316731743099;6528.869422611616;6543.770968016097;6626.204855417381;6460.67696615158;6467;6503.049085274493;6515.218173819172;6551.770688025897;6500.187476565429;6642.43539299165;6653.73385064592;6656.367645073904;6696.397324240842;6403;6403.662663128018;6445.387688169616;6452.548321617442;6541.6402097885075 13:57:16 INFO - PID 11216 | |5;yui.html;961.6057416459253;982.6069572171133;993.9055789699976;1003.4932359158628;1021.0300214795943;21227.893860530694;21701.240456584022;21726;21745.891270543627;21777.23779667712;18615.255389784394;18997.240110395567;19000.809991900078;19032.904835475838;19179.13693883775;1037.3723897042275;1056.7833594113226;1057.0750593230923;1058.1376178414605;1059.0362769879412;1041.8489319048733;1042.7705904700993;1046.3930920066382;1060.0459586372249;1078.0890147825114;1131.9547218111306;1133.161460519214;1133.7165708572857;1135.3982389333626;1141.1042331769563;1125.5779082843933;1125.6341688951125;1125.8423820665114;1126.498708074907;1136.8692600350983;828.4490813608943;828.6395417993192;829.9004119505638;830.0744669693308;836.3727204596553;20671.276505322392;21270.36188914335;21468.677969830464;21516.677249841265;21821.127154913785;23599.646005309933;23658.17196398135;23999.760002399926;24032;24174.395640108964;17935.73096403555;18356.724649130272;18475;18604.44186674402;18607.255709771594;4005.97997010016;4031.596840315974;4034.4553485279407;4036.2936486114813;4054.493188351456;155027;155394;158308;160089;161951.1902440492;17586.472405827844;18080.18639161241;18105.094745262682;18141.911485310924;18192.63614727698;33416.331673366396;33777.50787717087;33955.490667640006;34256.14359640979;34339.4849077264;9517.952410238044;9562.665306714232;9582.952085239529;9636.132748052709;9647.1317581418;4617.607503362185;4648.604868586141;4721.551452611996;4776.259679749644;4795.760211989386;6081.908771368433;6136.2636483622255;6186.783462578788;6192.690365481708;6193.071039344044;1166.550877911993;1182.6274723462254;1185.7806305833276;1187.5309252845077;1192.362086283834;699.105145413871;711.2070041903344;723.5405517496428;727.316322656701;729.3545212486881;11909.76180476403;11939.46272417726;12113.878861211275;12583.748325033448;12625.368731563385;712.3482013957171;723.7177500774702;730.0655161393424;740.1710084705164;744.1070715141851;5837.708114594253;5881.941180588139;5891.823245302648;5995.970020149958;6020.608660437058;147563;148962;149718;150528;154304.22847885912;135099.32450337883;135662;137459;137717;137815;114847;115968.42015789867;117508.41245793887;117994.41002794931;119187;11303.43482825839;11328.773424531299;11459.369734664673;11487.827682584935;11608.419579020847;56810.431895680515;57412;58479.152084792884;58696.413035869096;59180;72416;72884;72898.63550682424;73461.6326918362;75930.62034689791;16870.831291687417;17184.22670979834;17742.91128544349;17786.733199001766;17859.553511161805;17269.309227631256;17620.11899404999;18602.162902669683;18723.157457914698;18844.52888677794;15922.522324330288;16056.43802466908;16235.43175988882;16288.837111628733;16431.178441078137;16333.428330008393;16629.91685041567;16738;16746.246418910934;16786.748198776782;15317.310721017358;15363.15502647365;15455.768163477782;15581.610459738597;15640.139792311533;12530.686732831753;12531.624051278477;12674.176178548365;12695.682607934876;12777.93611031939;11633.185677002528;11791.233569817934;11799.339499992488;11801.70495737579;11856.70358241051 13:57:16 INFO - PID 11216 | __end_tp_report 13:57:16 INFO - PID 11216 | __start_cc_report 13:57:16 INFO - PID 11216 | _x_x_mozilla_cycle_collect,357 13:57:16 INFO - PID 11216 | __end_cc_report 13:57:16 INFO - PID 11216 | __startTimestamp1505509036629__endTimestamp 13:57:16 INFO - PID 11216 | 13:57:16 INFO - PID 11216 | ------- Summary: start ------- 13:57:16 INFO - PID 11216 | Number of tests: 6 13:57:16 INFO - PID 11216 | 13:57:16 INFO - PID 11216 | [#0] dojo.html Cycles:165 Average:6032.78 Median:6309.59 stddev:832.22 (13.2%) stddev-sans-first:814.85 13:57:16 INFO - PID 11216 | Values: 3725.4 3742.3 3752.8 3762.7 3765.6 6004.8 6025.4 6034.3 6052.5 6097.3 6080.3 6126.7 6160.9 6161.0 6171.2 5430.9 5506.0 5508.9 5510.6 5566.3 388.9 5514.9 5569.4 5571.7 5584.1 5457.6 5465.7 5471.9 5545.9 5557.6 5453.3 5470.6 5524.5 5532.8 5535.9 5415.3 5524.8 5529.8 5542.1 5548.5 5970.9 5982.2 6106.7 6119.2 6129.3 6451.3 6519.6 6546.5 6601.7 6619.3 6531.4 6623.4 6641.4 6644.0 6653.6 6089.7 6162.2 6174.0 6204.7 6229.1 6672.4 6707.9 6787.4 6790.1 6829.6 6472.2 6480.1 6532.7 6555.7 6574.8 6494.6 6590.4 6614.7 6662.6 6663.4 6488.8 6494.8 6513.1 6516.7 6572.2 6555.9 6567.2 6585.4 6599.8 6645.0 4773.0 4774.9 4835.0 4840.1 4845.3 4379.5 4388.0 4409.8 4422.6 4432.2 5845.9 5903.0 5922.3 5971.9 6014.3 6631.1 6643.4 6656.2 6662.7 6672.0 5944.4 5951.4 5970.0 6031.3 6039.8 5322.5 5327.1 5350.2 5401.1 5415.8 6644.9 6683.7 6702.2 6739.4 6749.4 6621.4 6663.1 6663.8 6688.4 6705.5 6714.4 6715.3 6723.8 6733.1 6828.6 6477.4 6511.1 6549.4 6569.5 6613.2 6640.9 6650.6 6650.8 6654.7 6659.7 6294.2 6365.3 6379.6 6426.3 6454.1 6307.1 6310.4 6352.3 6400.6 6400.8 6309.6 6310.4 6386.9 6391.2 6411.8 6342.0 6388.8 6432.6 6469.0 6496.9 6181.3 6212.8 6215.4 6221.3 6257.8 13:57:16 INFO - PID 11216 | 13:57:16 INFO - PID 11216 | [#1] ext.html Cycles:180 Average:43054.52 Median:39210.80 stddev:63713.16 (162.5%) stddev-sans-first:63813.50 13:57:16 INFO - PID 11216 | Values: 962.1 977.8 993.0 994.6 996.4 44339.8 45378.0 45541.0 45639.3 45693.5 41758.8 41764.2 42054.6 42085.8 42087.0 7442.0 7548.8 7561.9 7598.4 7598.7 7311.3 7374.5 7377.4 7390.3 7405.3 7463.0 8051.3 8280.6 8302.4 8305.8 8150.2 8157.3 8195.5 8201.9 8219.6 7848.9 8072.3 8076.2 8079.9 8080.7 40989.6 41230.6 41620.0 41689.0 41703.6 3768.0 3801.9 3814.5 3819.4 3820.2 33661.0 33797.8 33822.5 33980.5 34016.3 5498.2 5502.4 5509.1 5528.4 5576.0 222205.0 222244.0 222580.0 223270.0 223484.0 46160.8 46994.3 51193.7 51213.0 51777.7 54246.0 54252.0 54319.2 54390.5 54435.5 15481.9 15488.3 15571.1 15614.5 15616.1 12299.9 12324.9 12386.3 12474.3 12478.3 14716.4 14797.1 14798.2 14836.8 14850.0 5812.7 5840.5 5879.8 5952.6 5969.7 1097.9 1120.2 1125.9 1133.0 1139.1 25510.6 25985.2 26019.6 26022.5 26179.2 1206.7 1219.9 1222.9 1236.6 1247.0 13532.3 13602.3 13632.5 13709.7 13856.2 269536.0 269826.0 269870.0 269927.0 273265.0 60014.4 60127.0 60182.7 60475.0 60589.0 1525.2 1544.4 1566.2 1571.3 1588.1 25132.7 25174.5 25314.6 25383.4 25470.2 41886.4 42172.6 42233.6 42261.8 42330.4 225596.0 228506.0 246292.0 248861.0 249218.0 34777.3 35016.3 35166.5 38386.4 42314.6 53555.5 53705.0 53726.5 53742.2 53916.2 38827.8 38924.2 39132.4 39351.2 39536.4 38869.6 38978.0 39086.8 39197.8 39223.8 46112.8 46363.0 46373.0 46695.0 46904.8 37803.6 37958.6 38378.0 38433.8 38539.0 27034.9 27088.2 27284.9 27486.3 28218.4 13:57:16 INFO - PID 11216 | 13:57:16 INFO - PID 11216 | [#2] jquery.html Cycles:180 Average:39583.83 Median:13491.29 stddev:97348.73 (721.6%) stddev-sans-first:97577.78 13:57:16 INFO - PID 11216 | Values: 584.4 591.2 594.2 598.5 603.6 23743.6 24388.0 24533.8 24564.9 24570.0 23232.5 24287.6 24777.4 24846.5 24870.5 6606.9 6789.4 6797.0 6798.6 6812.9 6521.3 6673.7 6681.4 6798.5 6830.4 6774.4 6806.9 6809.2 6810.0 6811.6 6793.4 6794.3 6796.6 6801.9 6811.9 6072.3 6277.7 6279.3 6286.6 6301.8 29920.6 30225.8 30266.1 30511.2 30539.8 2282.6 2284.4 2291.7 2311.7 2312.7 20234.5 20804.6 20863.9 21079.4 21127.0 2453.6 2483.4 2501.8 2517.5 2537.1 129139.0 135379.0 142559.0 150382.0 151504.0 15073.8 15397.5 15615.4 15683.2 16218.8 24529.0 24646.1 24727.5 24729.9 24757.9 9441.4 9449.9 9582.5 9706.0 9756.6 8415.2 8488.7 8491.3 8512.7 8584.7 9453.2 9587.5 9597.3 9657.2 10001.0 2784.7 2798.0 2856.7 2880.8 2881.6 1029.5 1044.7 1047.5 1061.7 1065.6 20658.7 20786.8 21153.5 21244.8 21475.1 1117.8 1123.9 1135.4 1140.0 1146.2 11329.6 11630.3 11783.2 11840.3 11930.9 560350.0 580475.0 581581.0 582077.0 600354.0 130941.0 131390.0 132964.3 133251.0 134070.0 1104.9 1155.1 1224.7 1235.1 1290.6 22111.6 22275.7 22419.2 22452.6 22497.6 65747.0 65822.7 66764.7 66795.3 69149.7 86789.0 87188.0 87460.0 87973.6 88523.0 21227.3 21840.7 21889.0 22085.0 22310.7 24661.9 24816.1 25098.7 25106.0 25170.7 20100.6 20200.6 20314.2 20691.9 20712.4 17657.2 17962.9 18064.4 18144.5 18412.4 20305.8 20430.6 20536.9 20695.7 20854.1 21412.1 21557.4 21617.5 21726.7 21759.2 19674.2 19727.2 19756.8 20088.3 20157.7 13:57:16 INFO - PID 11216 | 13:57:16 INFO - PID 11216 | [#3] mootools.html Cycles:180 Average:10255.98 Median:2867.45 stddev:7554.76 (263.5%) stddev-sans-first:7547.17 13:57:16 INFO - PID 11216 | Values: 1478.7 1536.2 1538.7 1541.1 1545.8 15996.3 17123.9 17141.2 17155.2 17156.9 16896.7 17000.0 17040.8 17059.2 17089.9 2862.3 2879.4 2919.3 2922.8 2931.8 2834.8 2871.4 2874.3 2877.2 2878.5 2682.8 2891.6 2903.5 2904.3 2904.7 2748.6 2863.5 2886.4 2887.3 2910.6 2852.8 2873.5 2874.4 2876.2 2880.4 16344.9 16364.1 16410.5 16420.0 16436.5 8217.4 8552.5 8579.0 8579.4 8585.7 5522.4 5773.8 5858.5 5874.3 5943.8 2083.4 2119.4 2142.4 2163.5 2178.9 28881.9 29494.0 29504.3 29547.4 29558.0 7032.2 7245.6 7282.3 7439.0 7458.1 18819.5 18922.5 18947.5 18950.8 19031.6 7922.0 8028.2 8032.0 8032.0 8062.7 7192.7 7263.9 7818.1 7859.3 8019.0 5606.9 5609.6 5708.2 5763.1 5937.4 1604.1 1623.7 1636.1 1655.7 1664.2 872.4 905.6 906.2 906.4 912.9 7335.9 7358.9 7406.5 7408.0 7408.3 771.6 799.6 802.7 814.4 822.1 5456.6 5473.8 5598.7 5827.2 5918.9 23886.4 24132.8 24394.6 25078.4 25169.2 23785.4 24014.9 24768.5 24801.3 25147.9 12121.0 12184.2 12309.7 12380.0 12439.3 9113.6 9117.9 9137.5 9274.1 9348.5 20476.0 20502.0 20795.4 21076.8 21229.3 21790.8 21831.3 21911.0 21947.8 22093.1 12094.6 12110.9 12264.6 12283.0 12418.8 15368.2 15369.4 15648.3 15663.1 15688.9 12455.8 12867.1 12873.6 12893.2 12932.5 12146.2 12382.6 12727.7 12734.7 12747.9 10568.4 10631.0 10679.9 10684.8 10697.1 7711.5 7718.4 7819.6 7831.4 7894.0 8251.3 8337.0 8388.9 8395.5 8434.1 13:57:16 INFO - PID 11216 | 13:57:16 INFO - PID 11216 | [#4] prototype.html Cycles:180 Average:5620.43 Median:6278.34 stddev:1577.25 (25.1%) stddev-sans-first:1534.10 13:57:16 INFO - PID 11216 | Values: 498.3 522.7 536.7 541.5 550.1 6154.0 6189.5 6281.3 6375.3 6379.1 6180.4 6274.0 6278.3 6285.5 6312.3 4859.0 5119.1 5133.2 5141.3 5151.2 4903.2 4944.2 4958.6 4981.6 5014.0 4945.2 4957.7 4992.4 5003.1 5007.4 4887.9 4937.1 4945.3 4983.5 4993.7 4493.4 4523.3 4523.9 4548.5 4560.6 6214.9 6278.4 6286.8 6291.8 6318.1 5809.8 5820.9 5843.5 5849.0 5851.8 6654.9 6672.5 6684.7 6700.0 6705.5 5032.7 5045.9 5053.4 5054.5 5062.3 6923.5 6946.4 6949.6 7004.8 7020.3 5112.2 5124.9 5146.7 5165.2 5193.4 5222.0 5244.2 5257.3 5262.5 5267.6 5721.1 5765.9 5791.9 5838.9 5840.2 6301.3 6489.9 6589.5 6611.5 6694.4 1726.6 1797.4 1798.7 1807.2 1817.9 1244.3 1246.2 1253.4 1260.7 1282.2 5942.4 6001.6 6084.2 6086.4 6118.2 6711.7 6758.4 6774.9 6790.4 6813.9 6059.7 6063.9 6082.7 6106.9 6130.9 3524.3 3614.9 3616.3 3621.8 3626.7 6899.3 6953.0 6954.7 7010.5 7017.3 6921.7 6982.8 7009.2 7087.4 7107.9 6904.1 6939.9 6960.7 6993.6 7008.3 6579.8 6666.6 6678.6 6699.6 6814.3 6595.5 6601.5 6626.0 6690.0 6698.9 6703.9 6803.9 6874.4 6900.6 6960.5 5073.4 5145.4 5156.3 5179.3 5183.5 6494.5 6743.9 6816.8 6860.5 6866.6 6309.7 6545.6 6556.0 6565.4 6581.4 6506.7 6507.3 6528.9 6543.8 6626.2 6460.7 6467.0 6503.0 6515.2 6551.8 6500.2 6642.4 6653.7 6656.4 6696.4 6403.0 6403.7 6445.4 6452.5 6541.6 13:57:16 INFO - PID 11216 | 13:57:16 INFO - PID 11216 | [#5] yui.html Cycles:180 Average:28372.29 Median:18007.96 stddev:42836.05 (237.9%) stddev-sans-first:42906.77 13:57:16 INFO - PID 11216 | Values: 961.6 982.6 993.9 1003.5 1021.0 21227.9 21701.2 21726.0 21745.9 21777.2 18615.3 18997.2 19000.8 19032.9 19179.1 1037.4 1056.8 1057.1 1058.1 1059.0 1041.8 1042.8 1046.4 1060.0 1078.1 1132.0 1133.2 1133.7 1135.4 1141.1 1125.6 1125.6 1125.8 1126.5 1136.9 828.4 828.6 829.9 830.1 836.4 20671.3 21270.4 21468.7 21516.7 21821.1 23599.6 23658.2 23999.8 24032.0 24174.4 17935.7 18356.7 18475.0 18604.4 18607.3 4006.0 4031.6 4034.5 4036.3 4054.5 155027.0 155394.0 158308.0 160089.0 161951.2 17586.5 18080.2 18105.1 18141.9 18192.6 33416.3 33777.5 33955.5 34256.1 34339.5 9518.0 9562.7 9583.0 9636.1 9647.1 4617.6 4648.6 4721.6 4776.3 4795.8 6081.9 6136.3 6186.8 6192.7 6193.1 1166.6 1182.6 1185.8 1187.5 1192.4 699.1 711.2 723.5 727.3 729.4 11909.8 11939.5 12113.9 12583.7 12625.4 712.3 723.7 730.1 740.2 744.1 5837.7 5881.9 5891.8 5996.0 6020.6 147563.0 148962.0 149718.0 150528.0 154304.2 135099.3 135662.0 137459.0 137717.0 137815.0 114847.0 115968.4 117508.4 117994.4 119187.0 11303.4 11328.8 11459.4 11487.8 11608.4 56810.4 57412.0 58479.2 58696.4 59180.0 72416.0 72884.0 72898.6 73461.6 75930.6 16870.8 17184.2 17742.9 17786.7 17859.6 17269.3 17620.1 18602.2 18723.2 18844.5 15922.5 16056.4 16235.4 16288.8 16431.2 16333.4 16629.9 16738.0 16746.2 16786.7 15317.3 15363.2 15455.8 15581.6 15640.1 12530.7 12531.6 12674.2 12695.7 12777.9 11633.2 11791.2 11799.3 11801.7 11856.7 13:57:16 INFO - PID 11216 | -------- Summary: end -------- 13:57:16 INFO - PID 11216 | 13:57:17 INFO - TEST-INFO | 11216: exit 0 13:57:17 INFO - TEST-OK | dromaeo_css | took 1107895ms 13:57:17 INFO - TEST-START | kraken 13:57:17 INFO - Initialising browser for kraken test... 13:57:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49156/getInfo.html -profile /tmp/tmp_ZHZKr/profile 13:57:17 INFO - TEST-INFO | started process 11589 (/builds/slave/test/build/application/firefox/firefox http://localhost:49156/getInfo.html) 13:57:22 INFO - TEST-INFO | 11589: exit 0 13:57:22 INFO - Browser initialized. 13:57:22 INFO - Running cycle 1/1 for kraken test... 13:57:22 INFO - Using env: {'DISPLAY': ':0', 13:57:22 INFO - 'HOME': '/home/cltbld', 13:57:22 INFO - 'JSGC_DISABLE_POISONING': '1', 13:57:22 INFO - 'LANG': 'en_US.UTF-8', 13:57:22 INFO - 'LANGUAGE': 'en_US:en', 13:57:22 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 13:57:22 INFO - 'LOGNAME': 'cltbld', 13:57:22 INFO - 'MAIL': '/var/mail/cltbld', 13:57:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:57:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:57:22 INFO - 'MOZ_CRASHREPORTER': '1', 13:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:22 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 13:57:22 INFO - 'MOZ_NO_REMOTE': '1', 13:57:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:57:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:57:22 INFO - 'NO_EM_RESTART': '1', 13:57:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:22 INFO - 'PWD': '/builds/slave/test', 13:57:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:57:22 INFO - 'RUST_BACKTRACE': 'full', 13:57:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:57:22 INFO - 'SHELL': '/bin/bash', 13:57:22 INFO - 'SHLVL': '1', 13:57:22 INFO - 'TERM': 'linux', 13:57:22 INFO - 'TMOUT': '86400', 13:57:22 INFO - 'USER': 'cltbld', 13:57:22 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475', 13:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:22 INFO - '_': '/tools/buildbot/bin/python'} 13:57:22 INFO - TEST-INFO | started process 11828 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_ZHZKr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 13:57:23 INFO - PID 11828 | 13:57:23 INFO - PID 11828 | (/builds/slave/test/build/application/firefox/firefox:11877): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 13:57:23 INFO - PID 11828 | 13:57:34 INFO - PID 11828 | RSS: Main: 165154816 13:57:34 INFO - PID 11828 | 13:57:36 INFO - PID 11828 | Cycle 1(1): loaded http://localhost:49156/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49156/tests/kraken/driver.html?testName=audio-beat-detection) 13:57:37 INFO - PID 11828 | RSS: Main: 170561536 13:57:37 INFO - PID 11828 | 13:57:40 INFO - PID 11828 | Cycle 1(1): loaded http://localhost:49156/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49156/tests/kraken/driver.html?testName=audio-dft) 13:57:40 INFO - PID 11828 | RSS: Main: 170999808 13:57:40 INFO - PID 11828 | 13:57:43 INFO - PID 11828 | Cycle 1(1): loaded http://localhost:49156/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49156/tests/kraken/driver.html?testName=audio-fft) 13:57:44 INFO - PID 11828 | RSS: Main: 165593088 13:57:44 INFO - PID 11828 | 13:57:46 INFO - PID 11828 | Cycle 1(1): loaded http://localhost:49156/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49156/tests/kraken/driver.html?testName=audio-oscillator) 13:57:47 INFO - PID 11828 | RSS: Main: 166866944 13:57:47 INFO - PID 11828 | 13:57:49 INFO - PID 11828 | Cycle 1(1): loaded http://localhost:49156/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49156/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:57:49 INFO - PID 11828 | RSS: Main: 167084032 13:57:49 INFO - PID 11828 | 13:57:52 INFO - PID 11828 | Cycle 1(1): loaded http://localhost:49156/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49156/tests/kraken/driver.html?testName=imaging-darkroom) 13:57:53 INFO - PID 11828 | RSS: Main: 170651648 13:57:53 INFO - PID 11828 | 13:57:57 INFO - PID 11828 | Cycle 1(1): loaded http://localhost:49156/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49156/tests/kraken/driver.html?testName=imaging-desaturate) 13:57:57 INFO - PID 11828 | RSS: Main: 170147840 13:57:57 INFO - PID 11828 | 13:58:00 INFO - PID 11828 | Cycle 1(1): loaded http://localhost:49156/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49156/tests/kraken/driver.html?testName=json-parse-financial) 13:58:01 INFO - PID 11828 | RSS: Main: 170176512 13:58:01 INFO - PID 11828 | 13:58:02 INFO - PID 11828 | Cycle 1(1): loaded http://localhost:49156/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49156/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:58:03 INFO - PID 11828 | RSS: Main: 170041344 13:58:03 INFO - PID 11828 | 13:58:04 INFO - PID 11828 | Cycle 1(1): loaded http://localhost:49156/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49156/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:58:05 INFO - PID 11828 | RSS: Main: 170020864 13:58:05 INFO - PID 11828 | 13:58:07 INFO - PID 11828 | Cycle 1(1): loaded http://localhost:49156/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49156/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:58:08 INFO - PID 11828 | RSS: Main: 166793216 13:58:08 INFO - PID 11828 | 13:58:10 INFO - PID 11828 | Cycle 1(1): loaded http://localhost:49156/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:58:11 INFO - PID 11828 | RSS: Main: 167067648 13:58:11 INFO - PID 11828 | 13:58:14 INFO - PID 11828 | Cycle 1(1): loaded http://localhost:49156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:58:14 INFO - PID 11828 | RSS: Main: 167481344 13:58:14 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | Cycle 1(1): loaded http://localhost:49156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49156/tests/kraken/driver.html?testName=ai-astar) 13:58:16 INFO - PID 11828 | RSS: Main: 169189376 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | __start_tp_report 13:58:16 INFO - PID 11828 | _x_x_mozilla_page_load 13:58:16 INFO - PID 11828 | _x_x_mozilla_page_load_details 13:58:16 INFO - PID 11828 | |i|pagename|runs| 13:58:16 INFO - PID 11828 | |0;ai-astar;106;105;106;108;106;111;109;105;110;105 13:58:16 INFO - PID 11828 | |1;audio-beat-detection;113;112;112;112;112;138;116;114;112;117 13:58:16 INFO - PID 11828 | |2;audio-dft;162;163;163;162;161;165;163;162;164;164 13:58:16 INFO - PID 11828 | |3;audio-fft;73;73;73;74;74;74;74;72;75;73 13:58:16 INFO - PID 11828 | |4;audio-oscillator;81;79;78;78;77;78;77;76;77;77 13:58:16 INFO - PID 11828 | |5;imaging-gaussian-blur;114;115;110;113;114;115;114;114;113;113 13:58:16 INFO - PID 11828 | |6;imaging-darkroom;149;149;148;149;149;151;150;154;149;151 13:58:16 INFO - PID 11828 | |7;imaging-desaturate;94;94;94;95;93;95;95;95;94;94 13:58:16 INFO - PID 11828 | |8;json-parse-financial;47;46;45;45;45;46;45;45;46;44 13:58:16 INFO - PID 11828 | |9;json-stringify-tinderbox;38;39;38;42;39;38;38;40;40;38 13:58:16 INFO - PID 11828 | |10;stanford-crypto-aes;73;79;70;71;74;74;77;72;72;71 13:58:16 INFO - PID 11828 | |11;stanford-crypto-ccm;107;110;107;108;111;107;104;109;108;114 13:58:16 INFO - PID 11828 | |12;stanford-crypto-pbkdf2;164;158;155;148;151;156;158;149;150;156 13:58:16 INFO - PID 11828 | |13;stanford-crypto-sha256-iterative;62;60;61;60;57;63;59;59;64;63 13:58:16 INFO - PID 11828 | __end_tp_report 13:58:16 INFO - PID 11828 | __start_cc_report 13:58:16 INFO - PID 11828 | _x_x_mozilla_cycle_collect,886 13:58:16 INFO - PID 11828 | __end_cc_report 13:58:16 INFO - PID 11828 | __startTimestamp1505509096678__endTimestamp 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | ------- Summary: start ------- 13:58:16 INFO - PID 11828 | Number of tests: 14 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | [#0] ai-astar Cycles:10 Average:107.10 Median:107.00 stddev:2.23 (2.1%) stddev-sans-first:2.33 13:58:16 INFO - PID 11828 | Values: 106.0 105.0 106.0 108.0 106.0 111.0 109.0 105.0 110.0 105.0 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | [#1] audio-beat-detection Cycles:10 Average:115.80 Median:113.50 stddev:8.01 (7.1%) stddev-sans-first:8.43 13:58:16 INFO - PID 11828 | Values: 113.0 112.0 112.0 112.0 112.0 138.0 116.0 114.0 112.0 117.0 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | [#2] audio-dft Cycles:10 Average:162.90 Median:163.00 stddev:1.20 (0.7%) stddev-sans-first:1.22 13:58:16 INFO - PID 11828 | Values: 162.0 163.0 163.0 162.0 161.0 165.0 163.0 162.0 164.0 164.0 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | [#3] audio-fft Cycles:10 Average:73.50 Median:74.00 stddev:0.85 (1.1%) stddev-sans-first:0.88 13:58:16 INFO - PID 11828 | Values: 73.0 73.0 73.0 74.0 74.0 74.0 74.0 72.0 75.0 73.0 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:78.00 stddev:1.40 (1.8%) stddev-sans-first:0.88 13:58:16 INFO - PID 11828 | Values: 81.0 79.0 78.0 78.0 77.0 78.0 77.0 76.0 77.0 77.0 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:114.00 stddev:1.43 (1.3%) stddev-sans-first:1.51 13:58:16 INFO - PID 11828 | Values: 114.0 115.0 110.0 113.0 114.0 115.0 114.0 114.0 113.0 113.0 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | [#6] imaging-darkroom Cycles:10 Average:149.90 Median:149.50 stddev:1.73 (1.2%) stddev-sans-first:1.80 13:58:16 INFO - PID 11828 | Values: 149.0 149.0 148.0 149.0 149.0 151.0 150.0 154.0 149.0 151.0 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 13:58:16 INFO - PID 11828 | Values: 94.0 94.0 94.0 95.0 93.0 95.0 95.0 95.0 94.0 94.0 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | [#8] json-parse-financial Cycles:10 Average:45.40 Median:45.50 stddev:0.84 (1.9%) stddev-sans-first:0.67 13:58:16 INFO - PID 11828 | Values: 47.0 46.0 45.0 45.0 45.0 46.0 45.0 45.0 46.0 44.0 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | [#9] json-stringify-tinderbox Cycles:10 Average:39.00 Median:39.00 stddev:1.33 (3.4%) stddev-sans-first:1.36 13:58:16 INFO - PID 11828 | Values: 38.0 39.0 38.0 42.0 39.0 38.0 38.0 40.0 40.0 38.0 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | [#10] stanford-crypto-aes Cycles:10 Average:73.30 Median:73.50 stddev:2.83 (3.9%) stddev-sans-first:3.00 13:58:16 INFO - PID 11828 | Values: 73.0 79.0 70.0 71.0 74.0 74.0 77.0 72.0 72.0 71.0 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | [#11] stanford-crypto-ccm Cycles:10 Average:108.50 Median:108.50 stddev:2.72 (2.5%) stddev-sans-first:2.83 13:58:16 INFO - PID 11828 | Values: 107.0 110.0 107.0 108.0 111.0 107.0 104.0 109.0 108.0 114.0 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.50 Median:156.00 stddev:4.99 (3.2%) stddev-sans-first:3.94 13:58:16 INFO - PID 11828 | Values: 164.0 158.0 155.0 148.0 151.0 156.0 158.0 149.0 150.0 156.0 13:58:16 INFO - PID 11828 | 13:58:16 INFO - PID 11828 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.80 Median:61.50 stddev:2.20 (3.6%) stddev-sans-first:2.29 13:58:16 INFO - PID 11828 | Values: 62.0 60.0 61.0 60.0 57.0 63.0 59.0 59.0 64.0 63.0 13:58:16 INFO - PID 11828 | -------- Summary: end -------- 13:58:16 INFO - PID 11828 | 13:58:17 INFO - PID 11828 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 13:58:17 INFO - TEST-INFO | 11828: exit 0 13:58:17 INFO - TEST-OK | kraken | took 59998ms 13:58:17 INFO - SUITE-END | took 1167s 13:58:17 INFO - Completed test suite (00:19:28) 13:58:17 INFO - javascript benchmark 13:58:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9004.47102060041, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3725.366687663097, 3742.2889650966313, 3752.81235938203, 3762.680172185364, 3765.566959799624, 6004.789832355868, 6025.427584379488, 6034.306054803699, 6052.515798736102, 6097.268327800664, 6080.331163572004, 6126.663033533165, 6160.938390616093, 6161.0, 6171.166892469515, 5430.864228394302, 5505.9724701376435, 5508.862278443031, 5510.641808282468, 5566.276384070065, 388.9045973651296, 5514.91727624086, 5569.415211402785, 5571.749271282782, 5584.050711379075, 5457.5633949283965, 5465.672059676433, 5471.917921231185, 5545.944540554624, 5557.6109672322955, 5453.345598528163, 5470.6444081135, 5524.475174858382, 5532.834014979557, 5535.889282214373, 5415.296011518478, 5524.8342549723575, 5529.751161197756, 5542.0855558832745, 5548.528375088123, 5970.880582388371, 5982.192404025444, 6106.725197366085, 6119.204503414572, 6129.325774164839, 6451.29035806061, 6519.641419721914, 6546.509011824132, 6601.6699165041555, 6619.304972977816, 6531.412172904462, 6623.403893649546, 6641.402273795334, 6643.966780166116, 6653.634050127241, 6089.665068421235, 6162.198914141133, 6173.999999999955, 6204.6587437690905, 6229.065640154013, 6672.366125218096, 6707.865842683216, 6787.389134977876, 6790.117284752951, 6829.590224586541, 6472.158619379545, 6480.060391243243, 6532.673366331759, 6555.704993275314, 6574.8356291093105, 6494.6103233806125, 6590.406863382319, 6614.7023383947835, 6662.56693314933, 6663.433608143265, 6488.772892948724, 6494.805155845333, 6513.088167656532, 6516.706748196342, 6572.178477690289, 6555.868882622321, 6567.2447668518735, 6585.440237579859, 6599.8020059398295, 6644.966775166094, 4773.021530586221, 4774.928376074362, 4834.975825120852, 4840.104580652591, 4845.3216549683075, 4379.5401482843845, 4388.034632380871, 4409.823607055746, 4422.624076953431, 4432.224360736858, 5845.883082338414, 5902.970485147633, 5922.318933322636, 5971.850703732354, 6014.338422773491, 6631.071649969008, 6643.435307998778, 6656.234533028667, 6662.666866656745, 6671.966640166768, 5944.435278648608, 5951.404859514014, 5970.044792833126, 6031.306399764083, 6039.758409663652, 5322.54758345545, 5327.067763141435, 5350.197470379397, 5401.081816091274, 5415.783368665209, 6644.933550664431, 6683.732650694015, 6702.229243637044, 6739.393454589111, 6749.358810912955, 6621.404073633396, 6663.13379260693, 6663.833404165031, 6688.431483323972, 6705.463562914858, 6714.395704386433, 6715.261321254756, 6723.798286051427, 6733.124693789776, 6828.556143850634, 6477.384648458389, 6511.055896895018, 6549.443297319591, 6569.507286953402, 6613.2394774601535, 6640.9335906642245, 6650.600963942179, 6650.76722314736, 6654.667266636746, 6659.7003134860015, 6294.244690637152, 6365.299817020032, 6379.585326953733, 6426.29310775805, 6454.128692626436, 6307.05394190875, 6310.400511951358, 6352.333005034405, 6400.647964362005, 6400.839979000562, 6309.589876658002, 6310.368963103653, 6386.872262554816, 6391.169148010915, 6411.775587854403, 6342.016987366966, 6388.8083357499345, 6432.6462044588, 6468.967655161694, 6496.870062598627, 6181.258249009968, 6212.844678883064, 6215.378462153929, 6221.2845522765465, 6257.843553911189], "value": 5979.451923595098, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [962.0523784072687, 977.8288799460092, 992.9702108936734, 994.5673631970094, 996.432033740768, 44339.77830110849, 45378.0, 45541.0, 45639.31541026883, 45693.54306456934, 41758.79120604401, 41764.16471670572, 42054.57945420552, 42085.78957105218, 42087.0, 7442.032535770356, 7548.849023019537, 7561.886571701429, 7598.4301177411635, 7598.658060387296, 7311.341979221869, 7374.483786134972, 7377.446691498132, 7390.26097390262, 7405.333519983201, 7463.029806125196, 8051.315638170762, 8280.627371768285, 8302.418830681854, 8305.750827475185, 8150.184981501861, 8157.347412207039, 8195.549244791504, 8201.876971845368, 8219.630116644736, 7848.882266766003, 8072.273495385443, 8076.152004039609, 8079.878801817978, 8080.676772929076, 40989.590104099174, 41230.58769412328, 41620.0, 41689.0, 41703.58296417027, 3767.981160094182, 3801.904952376213, 3814.5041144651295, 3819.3507103792144, 3820.2359528094494, 33661.0, 33797.83101084479, 33822.49266261008, 33980.49029264563, 34016.31967360664, 5498.202760599731, 5502.438829959492, 5509.063459211903, 5528.419515950807, 5575.972120139373, 222205.0, 222244.0, 222580.0, 223270.0, 223484.0, 46160.76919615448, 46994.29508557374, 51193.7440312796, 51213.0, 51777.7411112942, 54246.0, 54252.0, 54319.18521222185, 54390.45609543933, 54435.45564544383, 15481.922590386976, 15488.303026363841, 15571.065736055873, 15614.531564053097, 15616.141112238709, 12299.877001230052, 12324.876751232552, 12386.256824590553, 12474.251544907152, 12478.313692746804, 14716.411343546139, 14797.112173269423, 14798.186099764402, 14836.777448338284, 14850.0, 5812.651240925558, 5840.532757379399, 5879.823605291848, 5952.642841429528, 5969.671668058211, 1097.91765617579, 1120.2270433400927, 1125.8930401611833, 1133.0312582741801, 1139.1342579639534, 25510.617340739904, 25985.22044338673, 26019.609705854426, 26022.479550408887, 26179.214623561325, 1206.6741979665442, 1219.9329036903055, 1222.9021678265717, 1236.5610208376077, 1246.975060498803, 13532.25572593497, 13602.31988400576, 13632.522861699794, 13709.725805483835, 13856.237906915216, 269536.0, 269826.0, 269870.0, 269927.0, 273265.0, 60014.399856000884, 60127.0, 60182.69908650516, 60475.0, 60589.0, 1525.2221367102636, 1544.4440001599414, 1566.216891554223, 1571.2615070916652, 1588.126530408294, 25132.693872863776, 25174.49651007006, 25314.620280696174, 25383.36541586475, 25470.235892923243, 41886.371704425066, 42172.57827421687, 42233.57766422419, 42261.78869105635, 42330.36504452497, 225596.0, 228506.0, 246292.0, 248861.0, 249218.0, 34777.30445391028, 35016.299674006885, 35166.472502911965, 38386.42420363753, 42314.57685423106, 53555.46444535661, 53705.0, 53726.462735372144, 53742.19386709281, 53916.19125713039, 38827.80586097051, 38924.22151556896, 39132.41301380539, 39351.2129757409, 39536.40695389629, 38869.61130388773, 38978.0, 39086.80456597813, 39197.8040109809, 39223.80388098041, 46112.7694361526, 46363.0, 46373.0, 46695.0, 46904.765476172404, 37803.62196378001, 37958.62041379551, 38378.0, 38433.807830960664, 38539.0, 27034.864825675744, 27088.1873543794, 27284.863575681993, 27486.312842179104, 28218.435631286848], "value": 18825.711745490942, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.374719050616, 591.1546488521412, 594.2275042444822, 598.5480961873787, 603.6076550242342, 23743.643845342332, 24388.02447902084, 24533.75466245337, 24564.877175614143, 24570.0, 23232.535349293004, 24287.635685464687, 24777.380565485873, 24846.50306993859, 24870.502589948235, 6606.933930660692, 6789.3889549940495, 6797.0144329072255, 6798.626075565842, 6812.863742725142, 6521.3478652134645, 6673.666316684171, 6681.365270299314, 6798.52410331277, 6830.419414349786, 6774.356436138559, 6806.931930680679, 6809.216940051883, 6809.965950170243, 6811.625360605165, 6793.354631310018, 6794.286599907013, 6796.592204467748, 6801.93198068018, 6811.863762724743, 6072.2713274406915, 6277.748890044393, 6279.309275979638, 6286.591371560834, 6301.810945671591, 29920.551191732142, 30225.848870755726, 30266.092017239516, 30511.23721906948, 30539.847300763577, 2282.6119559674894, 2284.4288927768057, 2291.724993000834, 2311.6879221305244, 2312.7224733032144, 20234.49413764653, 20804.583908321747, 20863.89568052165, 21079.367618971457, 21127.0, 2453.570625140593, 2483.379155211197, 2501.837380570257, 2517.546841568517, 2537.0612873236837, 129139.0, 135379.0, 142559.0, 150381.9999999989, 151504.0, 15073.786368991849, 15397.461088861835, 15615.3753849847, 15683.215839207995, 16218.75671864923, 24529.0, 24646.137385191432, 24727.5054498909, 24729.87635061849, 24757.876210618833, 9441.386309889835, 9449.90550094504, 9582.520873956275, 9705.951470242604, 9756.609735610638, 8415.242628163494, 8488.74533763988, 8491.320694344562, 8512.70205542803, 8584.74245772628, 9453.196478299284, 9587.472689002172, 9597.280203984728, 9657.22742180624, 10000.999900009941, 2784.7215278472395, 2798.0, 2856.714328567168, 2880.8271503709843, 2881.5533592293227, 1029.4955471818755, 1044.6813721814767, 1047.5338474378827, 1061.7292590389557, 1065.6376831877199, 20658.690119648218, 20786.792132078786, 21153.471163221042, 21244.787552124282, 21475.140994360365, 1117.776444711061, 1123.8538989931258, 1135.4379582106744, 1140.049727693546, 1146.1919346860443, 11329.603463878724, 11630.302181869114, 11783.234089784137, 11840.348780817138, 11930.880691192977, 560350.0, 580475.0, 581581.0, 582077.0, 600354.0, 130941.0, 131390.0, 132964.3351783235, 133251.0, 134070.0, 1104.939228342433, 1155.070168514354, 1224.7305592769576, 1235.110720281396, 1290.6257185416125, 22111.557768844854, 22275.66586501236, 22419.215327463462, 22452.550948981254, 22497.550048999256, 65747.0, 65822.67088664525, 66764.6661766688, 66795.33204667892, 69149.65425172843, 86789.0, 87188.0, 87460.0, 87973.56013219892, 88523.0, 21227.257046003935, 21840.672389913845, 21889.0, 22085.0, 22310.665340019586, 24661.87669061643, 24816.131435399675, 25098.74901250964, 25106.0, 25170.74829251684, 20100.59798804045, 20200.59598808045, 20314.187432502535, 20691.896540517202, 20712.378628641163, 17657.205425755616, 17962.91018544899, 18064.36774712879, 18144.45566633003, 18412.35556755507, 20305.79694203039, 20430.591388172452, 20536.897315513328, 20695.68956465624, 20854.061567229208, 21412.072913437867, 21557.353279401643, 21617.459563511038, 21726.67409988883, 21759.238426654993, 19674.213031479154, 19727.210911563376, 19756.802431975495, 20088.296909608092, 20157.697634535787], "value": 13008.378008805857, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1478.652516658585, 1536.2088524409928, 1538.7076455473452, 1541.1446647110856, 1545.783590297358, 15996.280167392466, 17123.914380428098, 17141.228644710987, 17155.228014739336, 17156.914215428937, 16896.746548801748, 17000.0, 17040.829591704078, 17059.232334544944, 17089.91455042726, 2862.2844288927768, 2879.4385094906447, 2919.284775230061, 2922.8246305221755, 2931.794774365795, 2834.8015638905285, 2871.3970066286106, 2874.3101655602713, 2877.165621969637, 2878.481873262812, 2682.798790090741, 2891.5807207954836, 2903.5354343305075, 2904.288449329917, 2904.665963414203, 2748.6014527893544, 2863.4988876946554, 2886.393857289961, 2887.2781804548863, 2910.592517047615, 2852.7717782577342, 2873.454043731684, 2874.3820078682975, 2876.2090425133047, 2880.3807181456086, 16344.918275408547, 16364.099974501398, 16410.507684769476, 16420.0, 16436.506904792874, 8217.424780265384, 8552.5296108714, 8579.0, 8579.442336248125, 8585.699500517514, 5522.420145884705, 5773.769049238025, 5858.502027327682, 5874.295084589834, 5943.821685349447, 2083.3541602103396, 2119.4171602809197, 2142.3787101740477, 2163.5456554123493, 2178.880161591112, 28881.855590721912, 29493.999999999785, 29504.26239344012, 29547.409051819057, 29558.0, 7032.226455089936, 7245.637718114073, 7282.344588987016, 7439.0, 7458.105027396748, 18819.529511762317, 18922.52693682669, 18947.526311842037, 18950.81049189518, 19031.61936761257, 7922.0, 8028.237317454832, 8031.955845740016, 8032.0, 8062.717804876801, 7192.748253811092, 7263.927360726325, 7818.14000459949, 7859.331956783623, 8018.959905200553, 5606.887862242732, 5609.551235901118, 5708.20085188074, 5763.106718458645, 5937.435943585352, 1604.1337677653937, 1623.6590316033528, 1636.075617276235, 1655.6605895791333, 1664.192866459766, 872.4024043530202, 905.6105874473908, 906.1753804038293, 906.410832958569, 912.9409884533909, 7335.88996165058, 7358.889616655754, 7406.518576292524, 7407.962960185272, 7408.296211859864, 771.6026246483066, 799.5873097755965, 802.6949759091508, 814.4054839966857, 822.1490757066407, 5456.645318054393, 5473.808416705396, 5598.664080155204, 5827.184194212814, 5918.911216331759, 23886.402839929142, 24132.758672413052, 24394.634080488806, 25078.37304067413, 25169.24492265235, 23785.405364866016, 24014.87992560026, 24768.5046299073, 24801.25596232116, 25147.87426062858, 12121.0, 12184.20802647825, 12309.69225769345, 12380.0, 12439.253644781342, 9113.635454581743, 9117.90882091197, 9137.543122843697, 9274.072592740671, 9348.53257337144, 20476.0, 20502.0, 20795.37613871526, 21076.789232107483, 21229.256976005767, 21790.782092178877, 21831.345059648236, 21911.0, 21947.780522194575, 22093.116275348806, 12094.637160885188, 12110.878891210976, 12264.632061038183, 12283.017358611469, 12418.81371779442, 15368.154751488775, 15369.385224590891, 15648.295826688054, 15663.138527381103, 15688.92155539215, 12455.813162802384, 12867.099303048697, 12873.613791586267, 12893.226406415644, 12932.482700691868, 12146.210496317712, 12382.56661016896, 12727.681807954876, 12734.68163295925, 12747.872521274669, 10568.365898046142, 10631.0, 10679.946600266949, 10684.83972740425, 10697.090747286567, 7711.460197786101, 7718.382529397522, 7819.569923654026, 7831.412644051833, 7894.0132483439575, 8251.2986396157, 8336.958315208385, 8388.916110838814, 8395.538245396561, 8434.072252052392], "value": 7187.43523932495, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.31481712645115, 522.7464679630381, 536.6753114365808, 541.4720647368816, 550.1060776238612, 6154.0, 6189.53578481614, 6281.309056003836, 6375.3305902880165, 6379.07503412005, 6180.381961803829, 6273.968630156843, 6278.304857688421, 6285.528585356105, 6312.33720459352, 4859.0, 5119.104156772569, 5133.230015497668, 5141.280220769096, 5151.227315902626, 4903.19097348937, 4944.220080770938, 4958.611588755154, 4981.626378021645, 5013.974930125344, 4945.159322915095, 4957.702537847723, 4992.350994370745, 5003.099442100421, 5007.4491805901325, 4887.853364399074, 4937.086638971784, 4945.307656928033, 4983.526564976321, 4993.650444468889, 4493.415855938707, 4523.344115103291, 4523.864284071483, 4548.476260452752, 4560.589546940758, 6214.906776398358, 6278.372162783685, 6286.842828929268, 6291.779787707408, 6318.147050148263, 5809.841567938, 5820.912686309709, 5843.5033022193165, 5849.0, 5851.795187168471, 6654.900176497356, 6672.499562532781, 6684.732610695566, 6700.0, 6705.463562915004, 5032.7418145463635, 5045.91281062337, 5053.393592768891, 5054.545090941796, 5062.265971434158, 6923.507537286573, 6946.44428445718, 6949.5830250184645, 7004.81281017136, 7020.29797020299, 5112.156494178419, 5124.948750512522, 5146.724607277571, 5165.199394093921, 5193.37679478465, 5222.00781851447, 5244.213367994755, 5257.263983042377, 5262.500062494057, 5267.578593712494, 5721.113227449752, 5765.913511297334, 5791.913121303184, 5838.9124163137585, 5840.2115714378015, 6301.306856245809, 6489.929161688363, 6589.472842172615, 6611.537192396486, 6694.364035416627, 1726.611512409723, 1797.4248240563156, 1798.7032139697064, 1807.2228941555256, 1817.9272829086954, 1244.3280628460711, 1246.2211118051218, 1253.3733133433284, 1260.684828792802, 1282.1537785061814, 5942.37605051472, 6001.559625689803, 6084.178635884187, 6086.421789929949, 6118.20463339763, 6711.6979735912, 6758.357955994173, 6774.898376524356, 6790.354916282945, 6813.863722725518, 6059.666718330534, 6063.878722425527, 6082.684936709504, 6106.862598090633, 6130.877382452326, 3524.33037722837, 3614.855405783792, 3616.3309787689363, 3621.80880567253, 3626.6917312028245, 6899.275576064541, 6953.0, 6954.65226738661, 7010.544314619533, 7017.263187365355, 6921.7231310747, 6982.825429364307, 7009.228984811667, 7087.397571206505, 7107.92892071083, 6904.102466679299, 6939.93060069403, 6960.651967401508, 6993.580385176906, 7008.318003678978, 6579.795768417634, 6666.566673166229, 6678.632675202716, 6699.631520266432, 6814.250432452525, 6595.472362210916, 6601.50488713358, 6625.972974189009, 6690.0, 6698.899516507354, 6703.865922681422, 6803.863922721617, 6874.415674667709, 6900.6204658744255, 6960.512764106464, 5073.365829271341, 5145.434002259673, 5156.303898973741, 5179.274901513716, 5183.481651834786, 6494.512911531559, 6743.9325606743305, 6816.829579260559, 6860.485463590151, 6866.631012300748, 6309.684515774285, 6545.574537655038, 6556.0, 6565.44193743518, 6581.3747693969, 6506.73973041092, 6507.316731743099, 6528.869422611616, 6543.770968016097, 6626.204855417381, 6460.67696615158, 6467.0, 6503.049085274493, 6515.218173819172, 6551.770688025897, 6500.187476565429, 6642.43539299165, 6653.73385064592, 6656.367645073904, 6696.397324240842, 6403.0, 6403.662663128018, 6445.387688169616, 6452.548321617442, 6541.6402097885075], "value": 5158.137172036781, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [961.6057416459253, 982.6069572171133, 993.9055789699976, 1003.4932359158628, 1021.0300214795943, 21227.893860530694, 21701.240456584022, 21726.0, 21745.891270543627, 21777.23779667712, 18615.255389784394, 18997.240110395567, 19000.809991900078, 19032.904835475838, 19179.13693883775, 1037.3723897042275, 1056.7833594113226, 1057.0750593230923, 1058.1376178414605, 1059.0362769879412, 1041.8489319048733, 1042.7705904700993, 1046.3930920066382, 1060.0459586372249, 1078.0890147825114, 1131.9547218111306, 1133.161460519214, 1133.7165708572857, 1135.3982389333626, 1141.1042331769563, 1125.5779082843933, 1125.6341688951125, 1125.8423820665114, 1126.498708074907, 1136.8692600350983, 828.4490813608943, 828.6395417993192, 829.9004119505638, 830.0744669693308, 836.3727204596553, 20671.276505322392, 21270.36188914335, 21468.677969830464, 21516.677249841265, 21821.127154913785, 23599.646005309933, 23658.17196398135, 23999.760002399926, 24032.0, 24174.395640108964, 17935.73096403555, 18356.724649130272, 18475.0, 18604.44186674402, 18607.255709771594, 4005.97997010016, 4031.596840315974, 4034.4553485279407, 4036.2936486114813, 4054.493188351456, 155027.0, 155394.0, 158308.0, 160089.0, 161951.1902440492, 17586.472405827844, 18080.18639161241, 18105.094745262682, 18141.911485310924, 18192.63614727698, 33416.331673366396, 33777.50787717087, 33955.490667640006, 34256.14359640979, 34339.4849077264, 9517.952410238044, 9562.665306714232, 9582.952085239529, 9636.132748052709, 9647.1317581418, 4617.607503362185, 4648.604868586141, 4721.551452611996, 4776.259679749644, 4795.760211989386, 6081.908771368433, 6136.2636483622255, 6186.783462578788, 6192.690365481708, 6193.071039344044, 1166.550877911993, 1182.6274723462254, 1185.7806305833276, 1187.5309252845077, 1192.362086283834, 699.105145413871, 711.2070041903344, 723.5405517496428, 727.316322656701, 729.3545212486881, 11909.76180476403, 11939.46272417726, 12113.878861211275, 12583.748325033448, 12625.368731563385, 712.3482013957171, 723.7177500774702, 730.0655161393424, 740.1710084705164, 744.1070715141851, 5837.708114594253, 5881.941180588139, 5891.823245302648, 5995.970020149958, 6020.608660437058, 147563.0, 148962.0, 149718.0, 150528.0, 154304.22847885912, 135099.32450337883, 135662.0, 137459.0, 137717.0, 137815.0, 114847.0, 115968.42015789867, 117508.41245793887, 117994.41002794931, 119187.0, 11303.43482825839, 11328.773424531299, 11459.369734664673, 11487.827682584935, 11608.419579020847, 56810.431895680515, 57412.0, 58479.152084792884, 58696.413035869096, 59180.0, 72416.0, 72884.0, 72898.63550682424, 73461.6326918362, 75930.62034689791, 16870.831291687417, 17184.22670979834, 17742.91128544349, 17786.733199001766, 17859.553511161805, 17269.309227631256, 17620.11899404999, 18602.162902669683, 18723.157457914698, 18844.52888677794, 15922.522324330288, 16056.43802466908, 16235.43175988882, 16288.837111628733, 16431.178441078137, 16333.428330008393, 16629.91685041567, 16738.0, 16746.246418910934, 16786.748198776782, 15317.310721017358, 15363.15502647365, 15455.768163477782, 15581.610459738597, 15640.139792311533, 12530.686732831753, 12531.624051278477, 12674.176178548365, 12695.682607934876, 12777.93611031939, 11633.185677002528, 11791.233569817934, 11799.339499992488, 11801.70495737579, 11856.70358241051], "value": 9817.88443915636, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1376.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 106.0, 108.0, 106.0, 111.0, 109.0, 105.0, 110.0, 105.0], "value": 107.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 112.0, 112.0, 112.0, 138.0, 116.0, 114.0, 112.0, 117.0], "value": 115.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 162.0, 161.0, 165.0, 163.0, 162.0, 164.0, 164.0], "value": 162.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 74.0, 74.0, 74.0, 74.0, 72.0, 75.0, 73.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 78.0, 78.0, 77.0, 78.0, 77.0, 76.0, 77.0, 77.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 110.0, 113.0, 114.0, 115.0, 114.0, 114.0, 113.0, 113.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 148.0, 149.0, 149.0, 151.0, 150.0, 154.0, 149.0, 151.0], "value": 149.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 93.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 46.0, 45.0, 45.0, 45.0, 46.0, 45.0, 45.0, 46.0, 44.0], "value": 45.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 38.0, 42.0, 39.0, 38.0, 38.0, 40.0, 40.0, 38.0], "value": 39.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 79.0, 70.0, 71.0, 74.0, 74.0, 77.0, 72.0, 72.0, 71.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 110.0, 107.0, 108.0, 111.0, 107.0, 104.0, 109.0, 108.0, 114.0], "value": 108.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 158.0, 155.0, 148.0, 151.0, 156.0, 158.0, 149.0, 150.0, 156.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 61.0, 60.0, 57.0, 63.0, 59.0, 59.0, 64.0, 63.0], "value": 60.8, "unit": "score"}]}]} 13:58:17 INFO - Return code: 0 13:58:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:58:17 INFO - # TBPL SUCCESS # 13:58:17 INFO - Running post-action listener: _package_coverage_data 13:58:17 INFO - Running post-action listener: _resource_record_post_action 13:58:17 INFO - [mozharness: 2017-09-15 20:58:17.957501Z] Finished run-tests step (success) 13:58:17 INFO - Running post-run listener: _resource_record_post_run 13:58:18 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 126976; Write bytes: 329207808; Read time: 232; Write time: 323224 13:58:18 INFO - TinderboxPrint: CPU usage
13.1% 13:58:18 INFO - TinderboxPrint: I/O read bytes / time
126,976 / 232 13:58:18 INFO - TinderboxPrint: I/O write bytes / time
329,207,808 / 323,224 13:58:18 INFO - TinderboxPrint: CPU idle
8,219.4 (86.9%) 13:58:18 INFO - TinderboxPrint: CPU user
1,188.2 (12.6%) 13:58:18 INFO - TinderboxPrint: Swap in / out
0 / 0 13:58:18 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 9342976; Read time: 0; Write time: 632 13:58:18 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 13:58:18 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 126976; Write bytes: 318717952; Read time: 232; Write time: 322564 13:58:18 INFO - Running post-run listener: _upload_blobber_files 13:58:18 INFO - Blob upload gear active. 13:58:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:58:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:58:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:58:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:58:18 INFO - (blobuploader) - INFO - Open directory for files ... 13:58:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:58:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:58:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:58:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:58:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:58:20 INFO - (blobuploader) - INFO - Done attempting. 13:58:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:58:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:58:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:58:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:58:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:58:21 INFO - (blobuploader) - INFO - Done attempting. 13:58:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:58:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:58:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:58:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:58:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:58:22 INFO - (blobuploader) - INFO - Done attempting. 13:58:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:58:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:58:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:58:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:58:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:58:26 INFO - (blobuploader) - INFO - Done attempting. 13:58:26 INFO - (blobuploader) - INFO - Iteration through files over. 13:58:26 INFO - Return code: 0 13:58:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:58:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:58:26 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3694b6ba459cf405f4b5ed4dfcf16c73b021f49780ced46a91f1bf46917d3cef9389767c58ae59ed4723d21c4c6a1699d85abcd2bf744df12dd8a066bf40ed98", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce1efed4f6352af04d6b5d95089f73581b26e0bd39f77ac210b2b03d2fc9b99a5724fd1d28f62bf096716ee21ac8f10474c16b730cfdc3c14459a8a9df74c53e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/173724e34333f9ee0f9c64c8a24a5cee62c55da5fceec4fd7c326d714a479eedb3067c9ae0f3712bbd92ce1bf036a22ce4dbaeb23033a42252ee58b68c278fea", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e6960cbe79e76eede602833e53c6fdb50d42c46d747c0751cc1646c0cb7cba64f70f47765dfd58b0dc43acf9ceb602d0ac67c761311a3db55746da7e5acd63a"} 13:58:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:58:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:58:26 INFO - Contents: 13:58:26 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3694b6ba459cf405f4b5ed4dfcf16c73b021f49780ced46a91f1bf46917d3cef9389767c58ae59ed4723d21c4c6a1699d85abcd2bf744df12dd8a066bf40ed98", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce1efed4f6352af04d6b5d95089f73581b26e0bd39f77ac210b2b03d2fc9b99a5724fd1d28f62bf096716ee21ac8f10474c16b730cfdc3c14459a8a9df74c53e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/173724e34333f9ee0f9c64c8a24a5cee62c55da5fceec4fd7c326d714a479eedb3067c9ae0f3712bbd92ce1bf036a22ce4dbaeb23033a42252ee58b68c278fea", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e6960cbe79e76eede602833e53c6fdb50d42c46d747c0751cc1646c0cb7cba64f70f47765dfd58b0dc43acf9ceb602d0ac67c761311a3db55746da7e5acd63a"} 13:58:26 INFO - Running post-run listener: copy_logs_to_upload_dir 13:58:26 INFO - Copying logs to upload dir... 13:58:26 INFO - mkdir: /builds/slave/test/build/upload/logs 13:58:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.734121 ========= master_lag: 0.31 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-15 13:58:26.682768) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 13:58:26.689864) ========= 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=14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475 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/3694b6ba459cf405f4b5ed4dfcf16c73b021f49780ced46a91f1bf46917d3cef9389767c58ae59ed4723d21c4c6a1699d85abcd2bf744df12dd8a066bf40ed98", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce1efed4f6352af04d6b5d95089f73581b26e0bd39f77ac210b2b03d2fc9b99a5724fd1d28f62bf096716ee21ac8f10474c16b730cfdc3c14459a8a9df74c53e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/173724e34333f9ee0f9c64c8a24a5cee62c55da5fceec4fd7c326d714a479eedb3067c9ae0f3712bbd92ce1bf036a22ce4dbaeb23033a42252ee58b68c278fea", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e6960cbe79e76eede602833e53c6fdb50d42c46d747c0751cc1646c0cb7cba64f70f47765dfd58b0dc43acf9ceb602d0ac67c761311a3db55746da7e5acd63a"} build_url:https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011209 build_url: 'https://queue.taskcluster.net/v1/task/YkVsvYeGRWujvQ9mZm3eYw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3694b6ba459cf405f4b5ed4dfcf16c73b021f49780ced46a91f1bf46917d3cef9389767c58ae59ed4723d21c4c6a1699d85abcd2bf744df12dd8a066bf40ed98", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce1efed4f6352af04d6b5d95089f73581b26e0bd39f77ac210b2b03d2fc9b99a5724fd1d28f62bf096716ee21ac8f10474c16b730cfdc3c14459a8a9df74c53e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/173724e34333f9ee0f9c64c8a24a5cee62c55da5fceec4fd7c326d714a479eedb3067c9ae0f3712bbd92ce1bf036a22ce4dbaeb23033a42252ee58b68c278fea", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e6960cbe79e76eede602833e53c6fdb50d42c46d747c0751cc1646c0cb7cba64f70f47765dfd58b0dc43acf9ceb602d0ac67c761311a3db55746da7e5acd63a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 13:58:26.720577) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 13:58:26.720897) ========= 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=14ef55db36f7623abb31b9cf000001d5-1505507869.580227-441175475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004488 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 13:58:26.805399) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 13:58:26.805727) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 13:58:26.806031) ========= ========= Total master_lag: 0.48 =========