builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-057 starttime: 1504394281.58 results: success (0) revision: 39e9a6b187a3f71f46d02af000ff8203abe4fe71 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 16:18:01.579369) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 16:18:01.580065) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 16:18:01.580531) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 16:18:01.597901) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:18:01.598239) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-02 16:18:01-- 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% 54.5M=0s 2017-09-02 16:18:01 (54.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125107 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:18:01.739627) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:18:01.739972) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014582 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:18:01.790353) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:18:01.790818) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 39e9a6b187a3f71f46d02af000ff8203abe4fe71 --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 39e9a6b187a3f71f46d02af000ff8203abe4fe71 --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=934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-02 16:18:01,842 truncating revision to first 12 chars 2017-09-02 16:18:01,842 Setting DEBUG logging. 2017-09-02 16:18:01,842 attempt 1/10 2017-09-02 16:18:01,842 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/39e9a6b187a3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-02 16:18:02,367 unpacking tar archive at: mozilla-central-39e9a6b187a3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.908488 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:18:02.713312) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 16:18:02.713641) ========= script_repo_revision: 39e9a6b187a3f71f46d02af000ff8203abe4fe71 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 16:18:02.714056) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 16:18:02.714328) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 16:18:02.726544) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-09-02 16:18:02.726885) ========= /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=934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:18:02 INFO - MultiFileLogger online at 20170902 16:18:02 in /builds/slave/test 16:18:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:18:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:18:02 INFO - {'append_to_log': False, 16:18:02 INFO - 'base_work_dir': '/builds/slave/test', 16:18:02 INFO - 'blob_upload_branch': 'Firefox', 16:18:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:18:02 INFO - 'branch': 'Firefox', 16:18:02 INFO - 'buildbot_json_path': 'buildprops.json', 16:18:02 INFO - 'code_coverage': False, 16:18:02 INFO - 'config_files': ('talos/linux_config.py',), 16:18:02 INFO - 'default_actions': ('clobber', 16:18:02 INFO - 'read-buildbot-config', 16:18:02 INFO - 'download-and-extract', 16:18:02 INFO - 'populate-webroot', 16:18:02 INFO - 'create-virtualenv', 16:18:02 INFO - 'install', 16:18:02 INFO - 'setup-mitmproxy', 16:18:02 INFO - 'run-tests'), 16:18:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:18:02 INFO - 'disable_ccov_upload': False, 16:18:02 INFO - 'disable_stylo': False, 16:18:02 INFO - 'download_minidump_stackwalk': True, 16:18:02 INFO - 'download_symbols': 'ondemand', 16:18:02 INFO - 'enable_stylo': False, 16:18:02 INFO - 'enable_webrender': False, 16:18:02 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:18:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:18:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:18:02 INFO - 'gecko_profile': False, 16:18:02 INFO - 'gecko_profile_interval': 0, 16:18:02 INFO - 'installer_path': 'installer.exe', 16:18:02 INFO - 'log_level': 'info', 16:18:02 INFO - 'log_name': 'talos', 16:18:02 INFO - 'log_to_console': True, 16:18:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:18:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:18:02 INFO - 'opt_config_files': (), 16:18:02 INFO - 'pip_index': False, 16:18:02 INFO - 'suite': 'dromaeojs-e10s', 16:18:02 INFO - 'system_bits': '32', 16:18:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:18:02 INFO - 'title': 'talos-linux64-ix-057', 16:18:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:18:02 INFO - 'use_talos_json': True, 16:18:02 INFO - 'verify': 'False', 16:18:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:18:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:18:02 INFO - 'work_dir': 'build'} 16:18:02 INFO - [mozharness: 2017-09-02 23:18:02.917199Z] Running clobber step. 16:18:02 INFO - Running pre-action listener: _resource_record_pre_action 16:18:02 INFO - Running main action method: clobber 16:18:02 INFO - rmtree: /builds/slave/test/build 16:18:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:18:04 INFO - Running post-action listener: _resource_record_post_action 16:18:04 INFO - [mozharness: 2017-09-02 23:18:04.067856Z] Finished clobber step (success) 16:18:04 INFO - [mozharness: 2017-09-02 23:18:04.067954Z] Running read-buildbot-config step. 16:18:04 INFO - Running pre-action listener: _resource_record_pre_action 16:18:04 INFO - Running main action method: read_buildbot_config 16:18:04 INFO - Using buildbot properties: 16:18:04 INFO - { 16:18:04 INFO - "project": "", 16:18:04 INFO - "product": "firefox", 16:18:04 INFO - "who": "archaeopteryx@coole-files.de", 16:18:04 INFO - "installer_path": "public/build/target.tar.bz2", 16:18:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:18:04 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:18:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 16:18:04 INFO - "stage_platform": "linux64-pgo", 16:18:04 INFO - "basedir": "/builds/slave/test", 16:18:04 INFO - "buildnumber": 152, 16:18:04 INFO - "platform": "ubuntu64_hw", 16:18:04 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:18:04 INFO - "slavebuilddir": "test", 16:18:04 INFO - "taskId": "IB1PsEfRQMav1WSDOcL8IA", 16:18:04 INFO - "branch": "mozilla-central", 16:18:04 INFO - "script_repo_revision": "production", 16:18:04 INFO - "revision": "39e9a6b187a3f71f46d02af000ff8203abe4fe71", 16:18:04 INFO - "slavename": "talos-linux64-ix-057", 16:18:04 INFO - "repo_path": "mozilla-central" 16:18:04 INFO - } 16:18:04 INFO - Finding installer, test and symbols from parent task. 16:18:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IB1PsEfRQMav1WSDOcL8IA'}, attempt #1 16:18:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IB1PsEfRQMav1WSDOcL8IA'}, attempt #1 16:18:04 INFO - Task dependencies: Nws720cCQeS6-DN01YUqhw 16:18:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw'}, attempt #1 16:18:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.tar.bz2 16:18:05 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.test_packages.json 16:18:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.crashreporter-symbols.zip 16:18:05 INFO - Running post-action listener: _resource_record_post_action 16:18:05 INFO - [mozharness: 2017-09-02 23:18:05.436934Z] Finished read-buildbot-config step (success) 16:18:05 INFO - [mozharness: 2017-09-02 23:18:05.437147Z] Running download-and-extract step. 16:18:05 INFO - Running pre-action listener: _resource_record_pre_action 16:18:05 INFO - Running main action method: download_and_extract 16:18:05 INFO - mkdir: /builds/slave/test/build/tests 16:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:05 INFO - trying https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.test_packages.json 16:18:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:18:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:18:05 INFO - Downloaded 989 bytes. 16:18:05 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:18:05 INFO - Using the following test package requirements: 16:18:05 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:18:05 INFO - u'common': [u'target.common.tests.zip'], 16:18:05 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:18:05 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:18:05 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:18:05 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:18:05 INFO - u'mozbase': [u'target.common.tests.zip'], 16:18:05 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:18:05 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:18:05 INFO - u'web-platform': [u'target.common.tests.zip', 16:18:05 INFO - u'target.web-platform.tests.tar.gz'], 16:18:05 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:18:05 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:18:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.common.tests.zip 16:18:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:18:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.common.tests.zip into memory 16:18:07 INFO - Content-Length response header: 55680676 16:18:07 INFO - Bytes received: 55680676 16:18:18 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:18:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.common.tests.zip 16:18:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:18:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.common.tests.zip into memory 16:18:19 INFO - Content-Length response header: 55680676 16:18:19 INFO - Bytes received: 55680676 16:18:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.talos.tests.zip 16:18:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:18:31 INFO - Fetch https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.talos.tests.zip into memory 16:18:32 INFO - Content-Length response header: 14496650 16:18:32 INFO - Bytes received: 14496650 16:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:32 INFO - trying https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.tar.bz2 16:18:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:18:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:18:33 INFO - Downloaded 63421338 bytes. 16:18:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.tar.bz2 16:18:33 INFO - mkdir: /builds/slave/test/properties 16:18:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:18:33 INFO - Writing to file /builds/slave/test/properties/build_url 16:18:33 INFO - Contents: 16:18:33 INFO - build_url:https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.tar.bz2 16:18:33 INFO - Running post-action listener: _resource_record_post_action 16:18:33 INFO - Running post-action listener: find_tests_for_verification 16:18:33 INFO - Running post-action listener: set_extra_try_arguments 16:18:33 INFO - [mozharness: 2017-09-02 23:18:33.443130Z] Finished download-and-extract step (success) 16:18:33 INFO - [mozharness: 2017-09-02 23:18:33.443226Z] Running populate-webroot step. 16:18:33 INFO - Running pre-action listener: _resource_record_pre_action 16:18:33 INFO - Running main action method: populate_webroot 16:18:33 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:18:33 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:18:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:18:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:18:33 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:18:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:18:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:18:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:33 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:18:33 INFO - 'talos_options': ['--enable-stylo'], 16:18:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:18:33 INFO - 'tests': ['damp', 'tps']}, 16:18:33 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:18:33 INFO - 'talos_options': ['--enable-stylo'], 16:18:33 INFO - 'tests': ['damp', 'tps']}, 16:18:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:18:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:18:33 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:18:33 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:18:33 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:18:33 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:18:33 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:18:33 INFO - 'talos_options': ['--enable-stylo'], 16:18:33 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:18:33 INFO - 'other-e10s': {'tests': ['a11yr', 16:18:33 INFO - 'ts_paint', 16:18:33 INFO - 'tpaint', 16:18:33 INFO - 'sessionrestore', 16:18:33 INFO - 'sessionrestore_many_windows', 16:18:33 INFO - 'sessionrestore_no_auto_restore', 16:18:33 INFO - 'tabpaint']}, 16:18:33 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:18:33 INFO - 'tests': ['a11yr', 16:18:33 INFO - 'ts_paint', 16:18:33 INFO - 'tpaint', 16:18:33 INFO - 'sessionrestore', 16:18:33 INFO - 'sessionrestore_many_windows', 16:18:33 INFO - 'sessionrestore_no_auto_restore', 16:18:33 INFO - 'tabpaint']}, 16:18:33 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:18:33 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:18:33 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:18:33 INFO - 'tests': ['bloom_basic_singleton']}, 16:18:33 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:18:33 INFO - 'tests': ['bloom_basic']}, 16:18:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:18:33 INFO - 'tsvgr_opacity', 16:18:33 INFO - 'tart', 16:18:33 INFO - 'tscrollx', 16:18:33 INFO - 'tsvg_static']}, 16:18:33 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:18:33 INFO - 'tests': ['tsvgx', 16:18:33 INFO - 'tsvgr_opacity', 16:18:33 INFO - 'tart', 16:18:33 INFO - 'tscrollx', 16:18:33 INFO - 'tsvg_static']}, 16:18:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:18:33 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:18:33 INFO - 'talos_options': ['--enable-stylo'], 16:18:33 INFO - 'tests': ['tp5o']}, 16:18:33 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:18:33 INFO - 'talos_options': ['--mitmproxy', 16:18:33 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:18:33 INFO - '--firstNonBlankPaint'], 16:18:33 INFO - 'tests': ['tp6_google', 16:18:33 INFO - 'tp6_youtube', 16:18:33 INFO - 'tp6_amazon', 16:18:33 INFO - 'tp6_facebook']}, 16:18:33 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:18:33 INFO - 'talos_options': ['--enable-stylo', 16:18:33 INFO - '--mitmproxy', 16:18:33 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:18:33 INFO - '--firstNonBlankPaint'], 16:18:33 INFO - 'tests': ['tp6_google', 16:18:33 INFO - 'tp6_youtube', 16:18:33 INFO - 'tp6_amazon', 16:18:33 INFO - 'tp6_facebook']}, 16:18:33 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:18:33 INFO - 'talos_options': ['--enable-stylo', 16:18:33 INFO - '--stylo-threads=1', 16:18:33 INFO - '--mitmproxy', 16:18:33 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:18:33 INFO - '--firstNonBlankPaint'], 16:18:33 INFO - 'tests': ['tp6_google', 16:18:33 INFO - 'tp6_youtube', 16:18:33 INFO - 'tp6_amazon', 16:18:33 INFO - 'tp6_facebook']}, 16:18:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:18:33 INFO - 'talos_options': ['--xperf_path', 16:18:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:18:33 INFO - 'tests': ['tp5n']}, 16:18:33 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:18:33 INFO - 'talos_options': ['--enable-stylo', 16:18:33 INFO - '--xperf_path', 16:18:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:18:33 INFO - 'tests': ['tp5n']}}} 16:18:33 INFO - Running post-action listener: _resource_record_post_action 16:18:33 INFO - [mozharness: 2017-09-02 23:18:33.457606Z] Finished populate-webroot step (success) 16:18:33 INFO - [mozharness: 2017-09-02 23:18:33.457695Z] Running create-virtualenv step. 16:18:33 INFO - Running pre-action listener: _resource_record_pre_action 16:18:33 INFO - Running main action method: create_virtualenv 16:18:33 INFO - Creating virtualenv /builds/slave/test/build/venv 16:18:33 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:18:33 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:18:33 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:18:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:18:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:18:34 INFO - Installing distribute.............................................................................................................................................................................................done. 16:18:34 INFO - Installing pip...............done. 16:18:34 INFO - Return code: 0 16:18:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:18:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:18:34 INFO - Reading from file tmpfile_stdout 16:18:34 INFO - Output received: 16:18:34 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:18:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:34 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 0x1d431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b3090>, '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 0x20b3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20adf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:18:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:18:34 INFO - Using env: {'DISPLAY': ':0', 16:18:34 INFO - 'HOME': '/home/cltbld', 16:18:34 INFO - 'LANG': 'en_US.UTF-8', 16:18:34 INFO - 'LANGUAGE': 'en_US:en', 16:18:34 INFO - 'LOGNAME': 'cltbld', 16:18:34 INFO - 'MAIL': '/var/mail/cltbld', 16:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:34 INFO - 'MOZ_NO_REMOTE': '1', 16:18:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:34 INFO - 'NO_EM_RESTART': '1', 16:18:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:34 INFO - 'PWD': '/builds/slave/test', 16:18:34 INFO - 'SHELL': '/bin/bash', 16:18:34 INFO - 'SHLVL': '1', 16:18:34 INFO - 'TERM': 'linux', 16:18:34 INFO - 'TMOUT': '86400', 16:18:34 INFO - 'USER': 'cltbld', 16:18:34 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 16:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:34 INFO - '_': '/tools/buildbot/bin/python'} 16:18:35 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:18:35 INFO - Downloading/unpacking pip>=1.5 16:18:35 INFO - Running setup.py egg_info for package pip 16:18:35 INFO - warning: no files found matching 'pip/cacert.pem' 16:18:35 INFO - warning: no files found matching '*.html' under directory 'docs' 16:18:35 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:18:35 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:18:35 INFO - Installing collected packages: pip 16:18:35 INFO - Found existing installation: pip 1.1 16:18:35 INFO - Uninstalling pip: 16:18:35 INFO - Successfully uninstalled pip 16:18:35 INFO - Running setup.py install for pip 16:18:35 INFO - warning: no files found matching 'pip/cacert.pem' 16:18:35 INFO - warning: no files found matching '*.html' under directory 'docs' 16:18:35 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:18:35 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:18:35 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:18:35 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:18:35 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:18:35 INFO - Successfully installed pip 16:18:35 INFO - Cleaning up... 16:18:35 INFO - Return code: 0 16:18:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1d431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b3090>, '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 0x20b3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20adf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:18:35 INFO - Using env: {'DISPLAY': ':0', 16:18:35 INFO - 'HOME': '/home/cltbld', 16:18:35 INFO - 'LANG': 'en_US.UTF-8', 16:18:35 INFO - 'LANGUAGE': 'en_US:en', 16:18:35 INFO - 'LOGNAME': 'cltbld', 16:18:35 INFO - 'MAIL': '/var/mail/cltbld', 16:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:35 INFO - 'MOZ_NO_REMOTE': '1', 16:18:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:35 INFO - 'NO_EM_RESTART': '1', 16:18:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:35 INFO - 'PWD': '/builds/slave/test', 16:18:35 INFO - 'SHELL': '/bin/bash', 16:18:35 INFO - 'SHLVL': '1', 16:18:35 INFO - 'TERM': 'linux', 16:18:35 INFO - 'TMOUT': '86400', 16:18:35 INFO - 'USER': 'cltbld', 16:18:35 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 16:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:35 INFO - '_': '/tools/buildbot/bin/python'} 16:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:35 INFO - Downloading/unpacking psutil>=3.1.1 16:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:18:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:37 INFO - Installing collected packages: psutil 16:18:37 INFO - Running setup.py install for psutil 16:18:37 INFO - building 'psutil._psutil_linux' extension 16:18:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:18:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:18:37 INFO - building 'psutil._psutil_posix' extension 16:18:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:18:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:18:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:37 INFO - Successfully installed psutil 16:18:37 INFO - Cleaning up... 16:18:37 INFO - Return code: 0 16:18:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:37 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 0x1d431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b3090>, '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 0x20b3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20adf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:18:37 INFO - Using env: {'DISPLAY': ':0', 16:18:37 INFO - 'HOME': '/home/cltbld', 16:18:37 INFO - 'LANG': 'en_US.UTF-8', 16:18:37 INFO - 'LANGUAGE': 'en_US:en', 16:18:37 INFO - 'LOGNAME': 'cltbld', 16:18:37 INFO - 'MAIL': '/var/mail/cltbld', 16:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:37 INFO - 'MOZ_NO_REMOTE': '1', 16:18:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:37 INFO - 'NO_EM_RESTART': '1', 16:18:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:37 INFO - 'PWD': '/builds/slave/test', 16:18:37 INFO - 'SHELL': '/bin/bash', 16:18:37 INFO - 'SHLVL': '1', 16:18:37 INFO - 'TERM': 'linux', 16:18:37 INFO - 'TMOUT': '86400', 16:18:37 INFO - 'USER': 'cltbld', 16:18:37 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 16:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:37 INFO - '_': '/tools/buildbot/bin/python'} 16:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:18:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:18:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:18:39 INFO - Installing collected packages: mozsystemmonitor 16:18:39 INFO - Running setup.py install for mozsystemmonitor 16:18:39 INFO - Successfully installed mozsystemmonitor 16:18:39 INFO - Cleaning up... 16:18:39 INFO - Return code: 0 16:18:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:39 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 0x1d431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b3090>, '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 0x20b3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20adf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:18:39 INFO - Using env: {'DISPLAY': ':0', 16:18:39 INFO - 'HOME': '/home/cltbld', 16:18:39 INFO - 'LANG': 'en_US.UTF-8', 16:18:39 INFO - 'LANGUAGE': 'en_US:en', 16:18:39 INFO - 'LOGNAME': 'cltbld', 16:18:39 INFO - 'MAIL': '/var/mail/cltbld', 16:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:39 INFO - 'MOZ_NO_REMOTE': '1', 16:18:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:39 INFO - 'NO_EM_RESTART': '1', 16:18:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:39 INFO - 'PWD': '/builds/slave/test', 16:18:39 INFO - 'SHELL': '/bin/bash', 16:18:39 INFO - 'SHLVL': '1', 16:18:39 INFO - 'TERM': 'linux', 16:18:39 INFO - 'TMOUT': '86400', 16:18:39 INFO - 'USER': 'cltbld', 16:18:39 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 16:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:39 INFO - '_': '/tools/buildbot/bin/python'} 16:18:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:39 INFO - Downloading/unpacking jsonschema==2.5.1 16:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:18:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:40 INFO - Downloading functools32-3.2.3-2.tar.gz 16:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:18:40 INFO - warning: no files found matching '*.txt' 16:18:40 INFO - no previously-included directories found matching 'build' 16:18:40 INFO - no previously-included directories found matching 'dist' 16:18:40 INFO - no previously-included directories found matching '.git*' 16:18:40 INFO - Installing collected packages: jsonschema, functools32 16:18:40 INFO - Running setup.py install for functools32 16:18:40 INFO - warning: no files found matching '*.txt' 16:18:40 INFO - no previously-included directories found matching 'build' 16:18:40 INFO - no previously-included directories found matching 'dist' 16:18:40 INFO - no previously-included directories found matching '.git*' 16:18:40 INFO - Successfully installed jsonschema functools32 16:18:40 INFO - Cleaning up... 16:18:40 INFO - Return code: 0 16:18:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:40 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 0x1d431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b3090>, '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 0x20b3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20adf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:18:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:18:40 INFO - Using env: {'DISPLAY': ':0', 16:18:40 INFO - 'HOME': '/home/cltbld', 16:18:40 INFO - 'LANG': 'en_US.UTF-8', 16:18:40 INFO - 'LANGUAGE': 'en_US:en', 16:18:40 INFO - 'LOGNAME': 'cltbld', 16:18:40 INFO - 'MAIL': '/var/mail/cltbld', 16:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:40 INFO - 'MOZ_NO_REMOTE': '1', 16:18:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:40 INFO - 'NO_EM_RESTART': '1', 16:18:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:40 INFO - 'PWD': '/builds/slave/test', 16:18:40 INFO - 'SHELL': '/bin/bash', 16:18:40 INFO - 'SHLVL': '1', 16:18:40 INFO - 'TERM': 'linux', 16:18:40 INFO - 'TMOUT': '86400', 16:18:40 INFO - 'USER': 'cltbld', 16:18:40 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 16:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:40 INFO - '_': '/tools/buildbot/bin/python'} 16:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:18:41 INFO - Cleaning up... 16:18:41 INFO - Return code: 0 16:18:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:41 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 0x1d431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b3090>, '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 0x20b3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20adf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:18:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:18:41 INFO - Using env: {'DISPLAY': ':0', 16:18:41 INFO - 'HOME': '/home/cltbld', 16:18:41 INFO - 'LANG': 'en_US.UTF-8', 16:18:41 INFO - 'LANGUAGE': 'en_US:en', 16:18:41 INFO - 'LOGNAME': 'cltbld', 16:18:41 INFO - 'MAIL': '/var/mail/cltbld', 16:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:41 INFO - 'MOZ_NO_REMOTE': '1', 16:18:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:41 INFO - 'NO_EM_RESTART': '1', 16:18:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:41 INFO - 'PWD': '/builds/slave/test', 16:18:41 INFO - 'SHELL': '/bin/bash', 16:18:41 INFO - 'SHLVL': '1', 16:18:41 INFO - 'TERM': 'linux', 16:18:41 INFO - 'TMOUT': '86400', 16:18:41 INFO - 'USER': 'cltbld', 16:18:41 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 16:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:41 INFO - '_': '/tools/buildbot/bin/python'} 16:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:41 INFO - Downloading/unpacking blobuploader==1.2.4 16:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:42 INFO - Downloading blobuploader-1.2.4.tar.gz 16:18:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:18:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:18:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:42 INFO - Downloading docopt-0.6.1.tar.gz 16:18:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:18:42 INFO - Installing collected packages: blobuploader, requests, docopt 16:18:42 INFO - Running setup.py install for blobuploader 16:18:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:18:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:18:43 INFO - Running setup.py install for requests 16:18:43 INFO - Running setup.py install for docopt 16:18:43 INFO - Successfully installed blobuploader requests docopt 16:18:43 INFO - Cleaning up... 16:18:43 INFO - Return code: 0 16:18:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:43 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 0x1d431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b3090>, '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 0x20b3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20adf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:18:43 INFO - Using env: {'DISPLAY': ':0', 16:18:43 INFO - 'HOME': '/home/cltbld', 16:18:43 INFO - 'LANG': 'en_US.UTF-8', 16:18:43 INFO - 'LANGUAGE': 'en_US:en', 16:18:43 INFO - 'LOGNAME': 'cltbld', 16:18:43 INFO - 'MAIL': '/var/mail/cltbld', 16:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:43 INFO - 'MOZ_NO_REMOTE': '1', 16:18:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:43 INFO - 'NO_EM_RESTART': '1', 16:18:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:43 INFO - 'PWD': '/builds/slave/test', 16:18:43 INFO - 'SHELL': '/bin/bash', 16:18:43 INFO - 'SHLVL': '1', 16:18:43 INFO - 'TERM': 'linux', 16:18:43 INFO - 'TMOUT': '86400', 16:18:43 INFO - 'USER': 'cltbld', 16:18:43 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 16:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:43 INFO - '_': '/tools/buildbot/bin/python'} 16:18:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:43 INFO - Running setup.py (path:/tmp/pip-uy1jqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:43 INFO - Running setup.py (path:/tmp/pip-58Pzis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:43 INFO - Running setup.py (path:/tmp/pip-WEbSe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:43 INFO - Running setup.py (path:/tmp/pip-8N8Y7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:44 INFO - Running setup.py (path:/tmp/pip-ce2tXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:44 INFO - Running setup.py (path:/tmp/pip-1qwHZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:44 INFO - Running setup.py (path:/tmp/pip-u8dpRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:44 INFO - Running setup.py (path:/tmp/pip-9RcqNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:44 INFO - Running setup.py (path:/tmp/pip-2by_Dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:44 INFO - Running setup.py (path:/tmp/pip-6QFCe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:44 INFO - Running setup.py (path:/tmp/pip-VYRtrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:44 INFO - Running setup.py (path:/tmp/pip-3cV_9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:44 INFO - Running setup.py (path:/tmp/pip-mJBsfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:44 INFO - Running setup.py (path:/tmp/pip-PulhHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:44 INFO - Running setup.py (path:/tmp/pip-WMcxrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:44 INFO - Running setup.py (path:/tmp/pip-DVJg1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:44 INFO - Running setup.py (path:/tmp/pip-9qBMOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:18:44 INFO - Running setup.py install for manifestparser 16:18:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Running setup.py install for mozcrash 16:18:45 INFO - Running setup.py install for mozdebug 16:18:45 INFO - Running setup.py install for mozdevice 16:18:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Running setup.py install for mozfile 16:18:45 INFO - Running setup.py install for mozhttpd 16:18:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Running setup.py install for mozinfo 16:18:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Running setup.py install for mozInstall 16:18:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Running setup.py install for mozleak 16:18:45 INFO - Running setup.py install for mozlog 16:18:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Running setup.py install for moznetwork 16:18:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Running setup.py install for mozprocess 16:18:46 INFO - Running setup.py install for mozprofile 16:18:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Running setup.py install for mozrunner 16:18:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Running setup.py install for mozscreenshot 16:18:46 INFO - Running setup.py install for moztest 16:18:46 INFO - Running setup.py install for mozversion 16:18:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:18:46 INFO - Cleaning up... 16:18:46 INFO - Return code: 0 16:18:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:46 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 0x1d431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b3090>, '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 0x20b3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20adf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:18:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:18:46 INFO - Using env: {'DISPLAY': ':0', 16:18:46 INFO - 'HOME': '/home/cltbld', 16:18:46 INFO - 'LANG': 'en_US.UTF-8', 16:18:46 INFO - 'LANGUAGE': 'en_US:en', 16:18:46 INFO - 'LOGNAME': 'cltbld', 16:18:46 INFO - 'MAIL': '/var/mail/cltbld', 16:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:46 INFO - 'MOZ_NO_REMOTE': '1', 16:18:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:46 INFO - 'NO_EM_RESTART': '1', 16:18:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:46 INFO - 'PWD': '/builds/slave/test', 16:18:46 INFO - 'SHELL': '/bin/bash', 16:18:46 INFO - 'SHLVL': '1', 16:18:46 INFO - 'TERM': 'linux', 16:18:46 INFO - 'TMOUT': '86400', 16:18:46 INFO - 'USER': 'cltbld', 16:18:46 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 16:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:46 INFO - '_': '/tools/buildbot/bin/python'} 16:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:46 INFO - Running setup.py (path:/tmp/pip-H1G2oF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:47 INFO - Running setup.py (path:/tmp/pip-vz4b6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:47 INFO - Running setup.py (path:/tmp/pip-PDOBif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:47 INFO - Running setup.py (path:/tmp/pip-pzVJni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:47 INFO - Running setup.py (path:/tmp/pip-qIRShj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:47 INFO - Running setup.py (path:/tmp/pip-6OlptI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:47 INFO - Running setup.py (path:/tmp/pip-wvwMWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:47 INFO - Running setup.py (path:/tmp/pip-Pl0gqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:47 INFO - Running setup.py (path:/tmp/pip-sr2ZcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:47 INFO - Running setup.py (path:/tmp/pip-eAq6EN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:47 INFO - Running setup.py (path:/tmp/pip-AuwXEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:47 INFO - Running setup.py (path:/tmp/pip-FXl3pJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:47 INFO - Running setup.py (path:/tmp/pip-k74ekv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:47 INFO - Running setup.py (path:/tmp/pip-FXNhwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:47 INFO - Running setup.py (path:/tmp/pip-cAdJYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:48 INFO - Running setup.py (path:/tmp/pip-jjIMom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:48 INFO - Running setup.py (path:/tmp/pip-qVSUwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:18:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:49 INFO - Downloading blessings-1.6.tar.gz 16:18:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:18:49 INFO - Installing collected packages: blessings 16:18:49 INFO - Running setup.py install for blessings 16:18:49 INFO - Successfully installed blessings 16:18:49 INFO - Cleaning up... 16:18:49 INFO - Return code: 0 16:18:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:18:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:49 INFO - Reading from file tmpfile_stdout 16:18:49 INFO - Current package versions: 16:18:49 INFO - argparse == 1.2.1 16:18:49 INFO - blessings == 1.6 16:18:49 INFO - blobuploader == 1.2.4 16:18:49 INFO - docopt == 0.6.1 16:18:49 INFO - functools32 == 3.2.3-2 16:18:49 INFO - jsonschema == 2.5.1 16:18:49 INFO - manifestparser == 1.1 16:18:49 INFO - mozInstall == 1.13 16:18:49 INFO - mozcrash == 1.0 16:18:49 INFO - mozdebug == 0.1 16:18:49 INFO - mozdevice == 0.51 16:18:49 INFO - mozfile == 1.2 16:18:49 INFO - mozhttpd == 0.7 16:18:49 INFO - mozinfo == 0.10 16:18:49 INFO - mozleak == 0.1 16:18:49 INFO - mozlog == 3.5 16:18:49 INFO - moznetwork == 0.27 16:18:49 INFO - mozprocess == 0.25 16:18:49 INFO - mozprofile == 0.28 16:18:49 INFO - mozrunner == 6.13 16:18:49 INFO - mozscreenshot == 0.1 16:18:49 INFO - mozsystemmonitor == 0.3 16:18:49 INFO - moztest == 0.8 16:18:49 INFO - mozversion == 1.4 16:18:49 INFO - psutil == 3.1.1 16:18:49 INFO - requests == 1.2.3 16:18:49 INFO - wsgiref == 0.1.2 16:18:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:49 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 0x1d431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b3090>, '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 0x20b3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20adf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:18:49 INFO - Using env: {'DISPLAY': ':0', 16:18:49 INFO - 'HOME': '/home/cltbld', 16:18:49 INFO - 'LANG': 'en_US.UTF-8', 16:18:49 INFO - 'LANGUAGE': 'en_US:en', 16:18:49 INFO - 'LOGNAME': 'cltbld', 16:18:49 INFO - 'MAIL': '/var/mail/cltbld', 16:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:49 INFO - 'MOZ_NO_REMOTE': '1', 16:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:49 INFO - 'NO_EM_RESTART': '1', 16:18:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:49 INFO - 'PWD': '/builds/slave/test', 16:18:49 INFO - 'SHELL': '/bin/bash', 16:18:49 INFO - 'SHLVL': '1', 16:18:49 INFO - 'TERM': 'linux', 16:18:49 INFO - 'TMOUT': '86400', 16:18:49 INFO - 'USER': 'cltbld', 16:18:49 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 16:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:49 INFO - '_': '/tools/buildbot/bin/python'} 16:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:18:49 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:18:51 INFO - Installing collected packages: simplejson 16:18:51 INFO - Running setup.py install for simplejson 16:18:51 INFO - building 'simplejson._speedups' extension 16:18:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 16:18:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 16:18:52 INFO - Successfully installed simplejson 16:18:52 INFO - Cleaning up... 16:18:52 INFO - Return code: 0 16:18:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:52 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 0x1d431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b3090>, '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 0x20b3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20adf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:18:52 INFO - Using env: {'DISPLAY': ':0', 16:18:52 INFO - 'HOME': '/home/cltbld', 16:18:52 INFO - 'LANG': 'en_US.UTF-8', 16:18:52 INFO - 'LANGUAGE': 'en_US:en', 16:18:52 INFO - 'LOGNAME': 'cltbld', 16:18:52 INFO - 'MAIL': '/var/mail/cltbld', 16:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:52 INFO - 'MOZ_NO_REMOTE': '1', 16:18:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:52 INFO - 'NO_EM_RESTART': '1', 16:18:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:52 INFO - 'PWD': '/builds/slave/test', 16:18:52 INFO - 'SHELL': '/bin/bash', 16:18:52 INFO - 'SHLVL': '1', 16:18:52 INFO - 'TERM': 'linux', 16:18:52 INFO - 'TMOUT': '86400', 16:18:52 INFO - 'USER': 'cltbld', 16:18:52 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 16:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:52 INFO - '_': '/tools/buildbot/bin/python'} 16:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:18:52 INFO - Cleaning up... 16:18:52 INFO - Return code: 0 16:18:52 INFO - Running post-action listener: _resource_record_post_action 16:18:52 INFO - Running post-action listener: _start_resource_monitoring 16:18:52 INFO - Starting resource monitoring. 16:18:52 INFO - [mozharness: 2017-09-02 23:18:52.421964Z] Finished create-virtualenv step (success) 16:18:52 INFO - [mozharness: 2017-09-02 23:18:52.422559Z] Running install step. 16:18:52 INFO - Running pre-action listener: _resource_record_pre_action 16:18:52 INFO - Running main action method: install 16:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:52 INFO - Reading from file tmpfile_stdout 16:18:52 INFO - Detecting whether we're running mozinstall >=1.0... 16:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:18:52 INFO - Reading from file tmpfile_stdout 16:18:52 INFO - Output received: 16:18:52 INFO - Usage: mozinstall [options] installer 16:18:52 INFO - Options: 16:18:52 INFO - -h, --help show this help message and exit 16:18:52 INFO - -d DEST, --destination=DEST 16:18:52 INFO - Directory to install application into. [default: 16:18:52 INFO - "/builds/slave/test"] 16:18:52 INFO - --app=APP Application being installed. [default: firefox] 16:18:52 INFO - mkdir: /builds/slave/test/build/application 16:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:19:09 INFO - Reading from file tmpfile_stdout 16:19:09 INFO - Output received: 16:19:09 INFO - /builds/slave/test/build/application/firefox/firefox 16:19:09 INFO - Running post-action listener: _resource_record_post_action 16:19:09 INFO - [mozharness: 2017-09-02 23:19:09.120680Z] Finished install step (success) 16:19:09 INFO - [mozharness: 2017-09-02 23:19:09.120930Z] Running setup-mitmproxy step. 16:19:09 INFO - Running pre-action listener: _resource_record_pre_action 16:19:09 INFO - Running main action method: setup_mitmproxy 16:19:09 INFO - Skipping: mitmproxy is not required 16:19:09 INFO - Running post-action listener: _resource_record_post_action 16:19:09 INFO - [mozharness: 2017-09-02 23:19:09.122057Z] Finished setup-mitmproxy step (success) 16:19:09 INFO - [mozharness: 2017-09-02 23:19:09.122276Z] Running run-tests step. 16:19:09 INFO - Running pre-action listener: _resource_record_pre_action 16:19:09 INFO - Running pre-action listener: _set_gcov_prefix 16:19:09 INFO - Running main action method: run_tests 16:19:09 WARNING - Try message not found. 16:19:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:19:09 INFO - Python 2.7.3 16:19:09 INFO - Return code: 0 16:19:09 INFO - grabbing minidump binary from tooltool 16:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:09 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 0x20b3090>, '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 0x20b3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20adf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:19:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:19:09 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:19:09 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:19:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:19:09 INFO - Return code: 0 16:19:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:19:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:19:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:19:09 INFO - ENV: RUST_BACKTRACE is now full 16:19:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:19:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:19:09 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/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:19:09 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/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:19:09 INFO - Using env: {'DISPLAY': ':0', 16:19:09 INFO - 'HOME': '/home/cltbld', 16:19:09 INFO - 'LANG': 'en_US.UTF-8', 16:19:09 INFO - 'LANGUAGE': 'en_US:en', 16:19:09 INFO - 'LOGNAME': 'cltbld', 16:19:09 INFO - 'MAIL': '/var/mail/cltbld', 16:19:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:19:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:09 INFO - 'MOZ_NO_REMOTE': '1', 16:19:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:19:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:09 INFO - 'NO_EM_RESTART': '1', 16:19:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:09 INFO - 'PWD': '/builds/slave/test', 16:19:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:19:09 INFO - 'RUST_BACKTRACE': 'full', 16:19:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:19:09 INFO - 'SHELL': '/bin/bash', 16:19:09 INFO - 'SHLVL': '1', 16:19:09 INFO - 'TERM': 'linux', 16:19:09 INFO - 'TMOUT': '86400', 16:19:09 INFO - 'USER': 'cltbld', 16:19:09 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 16:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:09 INFO - '_': '/tools/buildbot/bin/python'} 16:19:09 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/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:19:09 INFO - mozversion application_buildid: 20170902215941 16:19:09 INFO - mozversion application_changeset: 39e9a6b187a3f71f46d02af000ff8203abe4fe71 16:19:09 INFO - mozversion application_display_name: Nightly 16:19:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:19:09 INFO - mozversion application_name: Firefox 16:19:09 INFO - mozversion application_remotingname: firefox 16:19:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:19:09 INFO - mozversion application_vendor: Mozilla 16:19:09 INFO - mozversion application_version: 57.0a1 16:19:09 INFO - mozversion platform_buildid: 20170902215941 16:19:09 INFO - mozversion platform_changeset: 39e9a6b187a3f71f46d02af000ff8203abe4fe71 16:19:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:19:09 INFO - mozversion platform_version: 57.0a1 16:19:09 INFO - using testdate: 1504394349 16:19:09 INFO - actual date: 1504394349 16:19:09 INFO - starting webserver on 'localhost:53172' 16:19:09 INFO - SUITE-START | Running 2 tests 16:19:09 INFO - TEST-START | dromaeo_css 16:19:09 INFO - Initialising browser for dromaeo_css test... 16:19:09 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53172/getInfo.html -profile /tmp/tmpsXahkX/profile 16:19:09 INFO - TEST-INFO | started process 7973 (/builds/slave/test/build/application/firefox/firefox http://localhost:53172/getInfo.html) 16:19:15 INFO - TEST-INFO | 7973: exit 0 16:19:15 INFO - Browser initialized. 16:19:15 INFO - Running cycle 1/1 for dromaeo_css test... 16:19:15 INFO - Using env: {'DISPLAY': ':0', 16:19:15 INFO - 'HOME': '/home/cltbld', 16:19:15 INFO - 'JSGC_DISABLE_POISONING': '1', 16:19:15 INFO - 'LANG': 'en_US.UTF-8', 16:19:15 INFO - 'LANGUAGE': 'en_US:en', 16:19:15 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:19:15 INFO - 'LOGNAME': 'cltbld', 16:19:15 INFO - 'MAIL': '/var/mail/cltbld', 16:19:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:19:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:19:15 INFO - 'MOZ_CRASHREPORTER': '1', 16:19:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:15 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:19:15 INFO - 'MOZ_NO_REMOTE': '1', 16:19:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:19:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:15 INFO - 'NO_EM_RESTART': '1', 16:19:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:15 INFO - 'PWD': '/builds/slave/test', 16:19:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:19:15 INFO - 'RUST_BACKTRACE': 'full', 16:19:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:19:15 INFO - 'SHELL': '/bin/bash', 16:19:15 INFO - 'SHLVL': '1', 16:19:15 INFO - 'TERM': 'linux', 16:19:15 INFO - 'TMOUT': '86400', 16:19:15 INFO - 'USER': 'cltbld', 16:19:15 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 16:19:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:15 INFO - '_': '/tools/buildbot/bin/python'} 16:19:15 INFO - TEST-INFO | started process 8163 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsXahkX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:19:16 INFO - PID 8163 | 16:19:16 INFO - PID 8163 | (/builds/slave/test/build/application/firefox/firefox:8218): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:19:16 INFO - PID 8163 | 16:19:26 INFO - PID 8163 | RSS: Main: 161796096 16:19:26 INFO - PID 8163 | 16:22:25 INFO - PID 8163 | Cycle 1(1): loaded http://localhost:53172/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53172/tests/dromaeo/cssquery-ext.html) 16:22:27 INFO - PID 8163 | RSS: Main: 163115008 16:22:27 INFO - PID 8163 | 16:25:28 INFO - PID 8163 | Cycle 1(1): loaded http://localhost:53172/tests/dromaeo/cssquery-ext.html (next: http://localhost:53172/tests/dromaeo/cssquery-jquery.html) 16:25:29 INFO - PID 8163 | RSS: Main: 161394688 16:25:29 INFO - PID 8163 | 16:28:30 INFO - PID 8163 | Cycle 1(1): loaded http://localhost:53172/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53172/tests/dromaeo/cssquery-mootools.html) 16:28:31 INFO - PID 8163 | RSS: Main: 163368960 16:28:31 INFO - PID 8163 | 16:31:33 INFO - PID 8163 | Cycle 1(1): loaded http://localhost:53172/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53172/tests/dromaeo/cssquery-prototype.html) 16:31:33 INFO - PID 8163 | RSS: Main: 161947648 16:31:33 INFO - PID 8163 | 16:34:37 INFO - PID 8163 | Cycle 1(1): loaded http://localhost:53172/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53172/tests/dromaeo/cssquery-yui.html) 16:34:37 INFO - PID 8163 | RSS: Main: 163139584 16:34:37 INFO - PID 8163 | 16:37:39 INFO - PID 8163 | Cycle 1(1): loaded http://localhost:53172/tests/dromaeo/cssquery-yui.html (next: http://localhost:53172/tests/dromaeo/cssquery-dojo.html) 16:37:39 INFO - PID 8163 | RSS: Main: 165806080 16:37:39 INFO - PID 8163 | 16:37:39 INFO - PID 8163 | __start_tp_report 16:37:39 INFO - PID 8163 | _x_x_mozilla_page_load 16:37:39 INFO - PID 8163 | _x_x_mozilla_page_load_details 16:37:39 INFO - PID 8163 | |i|pagename|runs| 16:37:39 INFO - PID 8163 | |0;dojo.html;3866.883993480196;3885.8251378687974;3904.609539046096;3915.295246855565;3943.0733777562286;6723.092382528357;6796.320367963207;6816.965915170424;6819.4544436445085;6838.042674025634;6739.0902228199175;6817.386435220829;6824.146981627297;6834.863302733942;6835.316468353175;6124.2957059938235;6136.631802091878;6164.383561643822;6179.536534759911;6185.752569897199;6085.847853803668;6112;6126.7549298028025;6132.938670613304;6175.505959523227;6152.323244443108;6168.4448399644025;6172.166757487749;6184.752609895621;6231.439170474657;6045.395460453963;6077.726502307385;6106.267247930276;6133.6319820810895;6176.8455788605415;5946.672932988684;5990.281166260077;5997.190379298826;6043.153958445821;6050.062240352752;6765.8646827063185;6829.009793579903;6835.418989385908;6840.589564626139;6922.446204303644;7311.1592166900955;7321;7386.556806591568;7423.294786995227;7470.58911759853;7387.556746595222;7424.888626670604;7428.814279642998;7494.887576686299;7519.32326090649;6904.551204171713;6915.89626155603;6930.826729331757;6934.133233345832;6947.617881016542;7514.774556763306;7538.3592394645975;7576.4317676174505;7609.923900760921;7643.923560764321;7238.52949558277;7264.455165862476;7332.670029848563;7356.411487081021;7358.77923662291;7296.629258277379;7323.963380183065;7363.815904602321;7374.963125184339;7391.85216295671;7305.700509930783;7353.558786472829;7371.636247294267;7387.150477695026;7406.296401841924;7307.3058059484265;7312.817179570553;7315.841826661148;7436;7446.553206807609;6264.812055638338;6305.8108256752375;6369.9681501592195;6394.872102558016;6411.070394792729;5451.618386712892;5500.312460942382;5544.944550554524;5559.19391688211;5562.082256427725;6296.401841825019;6306.936930630635;6349.0476428536085;6370.235571731422;6420.6147631142285;7310.811890682469;7311.524750891175;7390.22402647725;7419.258074192538;7426.554406735613;6382.234131904202;6398.328175541595;6410.839729006812;6411.743530258737;6441.516886233551;6649.933500664932;6672.699728512229;6673.065770792093;6754.898676519856;6762.256151823295;7151.070360853056;7155.89266161008;7224.4581656375985;7232.059832221883;7255.129384473897;7245.1290218230615;7396.186419493743;7409.407247420086;7448.3296503312795;7459.477836551389;7173.1392232932385;7173.856522869409;7269.709211631687;7270.636468176464;7273.818154546178;7189.712411503481;7264.963675181591;7276.163241227327;7301.160366557914;7369.410447164107;7330.193678695233;7387.963060184665;7427.368673662799;7444.664990075568;7446.441516886147;7043.436525077879;7110.57336559808;7149.642517873982;7163.64181790898;7172.0676312079095;7039;7054.153501579844;7071.575705457689;7151.570905745672;7155.284471552803;7055.576665400093;7073.929260707327;7124.679389427384;7132.750353737802;7134.036905017963;7123.394511466583;7155.821104472429;7181.461390395846;7258.854822903618;7275.8908616371855;6904.482163837833;6989.7553585624255;7002.1947476040905;7010.964945175241;7026 16:37:39 INFO - PID 8163 | |1;ext.html;846.5894041389924;900.4822227219348;913.1507697841009;940.7930255343822;1241.5344245907784;32454.83772581137;32913.506297405525;40847.38728919065;40929.59070409295;41203.58796412035;42202.36696449556;42434.57565424345;42478.575214247845;42519.787401063026;42574.78712606433;7294.781156565312;7843.411744119186;8146.633401496947;8147.918520814805;8151.714689985852;7674.309312161904;7741.341985931174;7744.225577442267;7745.457817952745;7763.262490063435;7710.498817576868;7737;7761.107472640634;7794.3764498839955;7798.571078590675;7701.730439434621;7716.5370077795515;7720.729774457923;7726.188750181206;7733.651985660631;7794.688212471494;7805.1804560520895;7827.178146294614;7828.530288182727;7836.725714599962;37461.62538374608;37507.24985500302;37562;37574;38619.42070868911;3703.5370578677666;3707.7775333480076;3730.08612889841;3746.1571146492092;3767.6420740029926;32294.677053229403;32497;32500.512492312635;32501.349973000408;32841.6715832841;5309.654872433318;5438.646487978269;5445.264889239941;5457.5633949283965;5499.065158922993;212903;213397.99999999846;214302;217070;220001;50592.71147375409;50942;51016.489835101915;51047.489525104276;51081;53663.73168134134;53742;53767;53819.73090134524;53830;13401.128926619738;15144.697106057818;15272.38910443592;15278.083315001135;15280.235988200546;10870;11339.319640821577;11439.142064345207;11466.77066458683;11473.082153427873;14662.706745865024;14684.26578671062;14733.484328048467;14759.409623615149;14860.331285092198;5646.632968857011;5755;5817.680027598522;5824.854378640568;5850.3564607893095;1081.1999120650662;1083.5774048121239;1085.2999815119204;1085.365061439067;1088.711491454765;25004;25400.49199016009;25420.364490887874;25501.61747573788;25537.48925021489;1167.8088349883071;1175.3124422213102;1179.5163982767024;1182.0720734223594;1193.0093836118172;13291.93354033243;13309.46762129504;13310.866891330963;13393.330333483287;13581.049326547047;264598;264952;265223;265456;265572;55543.44456555383;55631.72184139134;56122;56214.156787648215;56302.43697563054;1489.255372313843;1501.3544176004423;1522.1704171226709;1522.9086254824747;1527.6486408126193;24789.50420991534;24806.263561550517;24867.751322486543;24903.750962490867;25011.499770004863;39256.411153832145;39261;39281.803590981865;39303.60696393;39312.60687393204;217603;218033;218676;218968;219106;36115.819420903776;38243;38591.228175436896;38629.806850965564;38707;45737.31394029159;47695.28457073077;47734.522654774395;48197.27704084371;48362;35296.647033529334;35686.464703028956;35876.64123358837;35961;36091.09772255715;34924;35018.649813501535;35082.47376289307;35281.647183527835;35596.28807423889;40289.19421611493;41328;41664.3750343739;41671.74984750462;42017.57982420137;33836;33854.322913542084;33862.83068584641;34345.48481772726;34627.13432164216;25973.870130649226;26316.21051368462;26375;26717.396956182693;27228.863855680596 16:37:39 INFO - PID 8163 | |2;jquery.html;584.3718003146619;585.7744768264217;591.6213623281101;598.4314900844199;606.7481994972086;23009.769902300974;23170.65244021341;23991.76008239917;24016.51966960662;24036.1587344443;23352.883235583842;23861.522769544597;23865.880670596667;23895.64156537649;23924.521509569797;6730.59616423015;6868.828279293008;6876.209235937881;6888.862222755542;6892.827679308008;6560.573562718432;6601.438877695402;6661.26726060133;6775.288594697559;6808.080909077261;6763.357481039294;6777.695003724844;6778.423833974093;6778.49161312901;6780.08468856703;6729.528932974669;6753.729850805962;6765.086713293692;6771.356721111511;6775.356341147583;6300.747970081237;6308.211473565804;6308.968455157695;6315.463185629181;6334.683265836736;29985.70014299851;30789.538156927665;31357.8432107838;31650.841745791124;31669.20826979321;2200.4058904095964;2240.014393666798;2241.9663705044436;2242.932712018626;2243.069126312578;20614.690779638317;21163.68254476184;21174.788252117436;21188.78811211899;21197.89401052985;2400.3759022654235;2433.221369161869;2493.252024392693;2497.7876880465183;2550.9234722958154;149508;151399;151905;152364.2381788095;152522.23738881346;15389.384424623113;15946.043237405793;16050.75923861143;16219.189040547926;16685.666286674197;25416;25417;25435.236942891373;25446.363840904127;25547.48905021889;10027.398356098658;10045.246606504541;10087.546060427298;10208.387496750218;10229.74425639365;8227.341812654973;8959.864047583424;8991.910080899239;9114.088591140833;9187.678431254873;8820.514871681991;9621.374610650286;9686.612535498502;9701.17540009093;10095.192384609214;2763.198672384986;2765.7925655575914;2792.3019245188702;2875.5399136138117;2978.3298757779758;1055.5883205549842;1066.0245875024266;1068.9358638481715;1089.1831126655009;1091.9617813376494;21819.454513636967;22288.88855555744;22507.774922250566;22530.662040069412;22567.0973161075;1121.859767529059;1124.9550017999188;1129.8474705914762;1134.3987686526154;1142.0406858238953;11773.646790596296;12222.816657750142;12391.256524608552;12450.626481205578;12463.937680311541;577702;589220;594532;597766;610081;125888.37055814662;129991;130039;130942;132005;1205.8613259475273;1242.3974372429363;1280.308633337987;1283.6919139406475;1292.566990058341;19170.13734381984;19358.351273457734;19677;19778.208871644973;19931.302404415776;58160;60575;61120.38879611147;62718.68640656768;63552.682236588524;77571.61214193892;78278.2172178271;78922;79445;79882.60058699669;12685.175463594836;13118.803217951547;14676.266186690409;15093.849061509245;15457.304421301293;24611.261662150166;24704;24734.628980565667;25029.49941001206;25072.122475713262;19878.50303742418;20199.79800202038;20228;20362.592748144656;20388.490287742927;17534.298628054734;17730.64538709193;17827;17885.28458861631;17966.191521381308;18763.530911727317;18891.811081889005;18956.24175032983;19051.80948190556;19156.32952846644;15678.686426271182;15706;15722.842771572137;23009.309720708407;23560.88219558891;21101;21335.46661333479;21592.67610985805;21598.568028639023;21609.567808644053 16:37:39 INFO - PID 8163 | |3;mootools.html;1416.5821082780578;1453.4476898778469;1464.905991077118;1481.5925620454377;1488.3228131200299;16753.832461675378;17007.979521228735;17175.141242937865;17419.303227870885;17436.65126697465;17230.138493075327;17520.299188032495;17520.561985950357;17526.561835954108;17544.29822807086;2870.5550639650783;2930.2527855396843;2932.135020169055;2934.251765799718;2934.7065293470587;2577.6520169777123;2630.302969713028;2642.484715480482;2658.8670566471696;2842.715728427151;2708.3093811078247;2750.2849259192553;2756.641636587241;2758.7517123458883;2759.8344099354003;2642.8150029497942;2652.0717748787865;2747.4230411613485;2752.0918097028134;2819.3797364579964;2682.329417645589;2691.6097165910833;2705.60768688541;2720.319920019995;2729.7816174705977;15988;16241.2691428886;16340;16345.182740862909;16588.66822663552;8585.35609829259;8866.86699699505;8868.822623547558;8870.822583548357;8870.955645221797;5479.780808767645;5589.720513974285;5661.433856614306;5674.687892165929;5741.33974592923;2033.4509682385822;2059.6395630764555;2062.7009083682933;2080.989595052015;2096.4549217203485;30094.24764380886;30302.393952121056;30373.392532149453;30444.695553044407;30618.23454413635;7232.963835180791;7236.710531578783;7266.45501587383;7453.776386708408;7459.694553453124;19004;19138.52153696141;19149.712754308697;19158.61682766337;19168.61662766737;8439.240468357877;8613.44012639189;8624.353173512012;8624.698135565224;8667.869981950276;7783.844323113506;7892.210778922062;7947.245011723877;7991.920080799117;8007.559584222807;5552.944470555323;5744.540436765133;5748.45389687979;5774.451427114417;5792.478676919098;1646.7118254305449;1672.347784364099;1687.1058339080307;1688.9408870689467;1714.7170716831583;927.8654595083675;948.7390967483997;955.2787645327872;965.1217392173229;966.3042609321277;8099.433039687283;8099.514029158269;8105.230003149692;8144.104148543655;8191.139930307351;785.2618538573722;795.7095660084028;795.9602019898982;797.4139007829241;809.2271880354248;5586.748596313176;5609.635373700697;5677.2619559457835;5714.542836573064;5924.785418989183;24200.152994645105;25233.243002709947;25426.618600721005;25783.484330313662;25994;24932.626010609856;25523.23430297094;25627.231183064538;25740.742592573835;25811.096611618505;10555.894441055645;11886.821697674543;12891.548795792101;13092.738145237043;13125.146865453715;9741.707748767549;10184;10299.639512617023;10345.017223363768;10481.842772358268;21054.68417973701;21438;21873.890630546746;22098.33704988853;22543.436414089778;21707.348779536638;22055.448613784785;22176.223832165797;22201.777982220618;22237.444063898532;13504.324783760969;13775.242361670204;13815.723685526033;13847.584572462842;13853.584392468241;15093.32080056378;15126.697466050397;15173.468928587446;15199.316030778862;15326;11469.885301147215;11500.654980350602;11529.192956492965;11707.239029463057;11781.941090294493;11142.220044596801;11246.887531124585;11299.152563557931;11543.826842597537;11728.12039097054;9296.349255552046;9519.190868776232;9534.761630959005;9546.093121153479;9552.665656701982;7616.961915190389;7680.5391676499585;7686.192949740197;7696.538207707556;7751.457397982087;7551.131619863786;7581.166071732216;7603.353714934293;7734.381249500139;7770.728024519115 16:37:39 INFO - PID 8163 | |4;prototype.html;529.8145649022842;546.5245236644121;559.8180591307826;567.9403662615425;575.7409165875356;6772.966135169323;6778.694958726863;6794.898076528843;6811.386975172233;6827.590344579328;6428.35716428357;6561.818872602929;6669.433098186648;6684.66576671167;6699.19609646843;5282.128448805943;5300.09898317285;5337.092694241969;5343.572514198864;5346.144616861303;5294.020606187848;5306.893862122755;5339.119045357531;5346.3960811756315;5352.895320713977;5085.567726743213;5248.501392367719;5266.078436273637;5272.261883336336;5277.498637629419;5211.348581427322;5241.685498870061;5257.526822585947;5270.31078245226;5281.498257665516;4699.060187962421;4717.976410117962;4721.929171062437;4724.244120940667;4741;6549.34506549346;6611.140551728293;6627.204735431731;6632.535722499427;6717.832054198635;6203.627782333075;6222.43998040174;6233.532485063638;6259.311475737665;6266.026201746436;7001.264867188923;7100.28997100291;7101.538400004035;7105.218425973138;7156.677949492233;5253.317068781034;5257.973710131425;5266.8419947401635;5298.655587386807;5307.973460132714;7507.662155203029;7551.225462016422;7575.3182213600485;7584.582847943361;7588.468807183499;5419.403865574784;5426.968875913563;5441;5480.698561579072;5504.40382280792;5489.505944465017;5507.476789704971;5532.917006244909;5537.141743029837;5537.972310138423;6114.174586430775;6232.127502149703;6233.438990490878;6245.5628106033055;6250.406211409909;7106.786796396116;7121.89317160243;7163.140423149255;7232.240614735377;7239.312265334785;1819.9363022294156;1860.6510280047128;1877.9952725291903;1891.101726679816;1914.444811004793;1274.5921305182437;1330.2683524061727;1336.953206637763;1337.1241836597044;1346.4748747988292;5492.972535137299;6145.84635384119;6237.126802247689;6237.500999919996;6246.094941043026;7045.8238544035785;7073.92926070743;7132.893006604905;7141.285871412817;7244.746433874789;6177.166082578884;6196.287426945959;6200.81397558074;6207.392894984117;6296;3808.504894363715;3829.751066180689;3836.3478208704587;3849.268638958589;3934.6852251819787;6925.272846351161;7084.752033678797;7104.21853596104;7138.357547820721;7138.785836424916;7305.379042781423;7391.6673749681395;7438.218987006395;7505.962470187614;7614.923850761532;7074.434045276263;7085.89371159433;7105.9644701778225;7131.643417829087;7154.177269614061;7190.280971902977;7201.3158749918675;7205.423566114803;7285.490015698851;7301.963490182515;7163.391111755559;7205.927940720735;7268.890966635399;7300.196978332273;7378.335949764332;6961;6963.2340442552295;7006.439484841098;7049.400800931979;7150.7497237596435;5343.732813359395;5368.141097424394;5421.159720243369;5435.537979271806;5451.781928722807;7316.9634151828905;7319.451041171827;7375.299346562068;7402.742793170987;7408.259174082548;6953.269906659728;6974.442044636316;7008.684609192496;7045.471589630901;7073.504854660125;6995.965020174866;7026.2271150174465;7026.859462810613;7044.647767611496;7062.858742825218;6911.274316196727;6965.721371145098;6990;6995.845685461842;7057.470689698191;6368.808935731936;7045.72373846164;7059.5764254144915;7120.821979450555;7131.750388736369;6749.358810912955;6754.324567543403;6766.748151591972;6781.186257649114;6934.271901450275 16:37:39 INFO - PID 8163 | |5;yui.html;952.3238500664528;976.2092704909024;996.1881066930451;1009.6264382178592;1010.4442556593872;19297;19819;19861.801381986177;19872.60254794905;23514.88242558789;20110.798892011077;20114.798852011474;20333.59332813343;20336.491587710316;20348.186072557117;1053.7997780421733;1069.5294070608945;1069.5507886687571;1069.5561342043043;1073.5115522437272;1073.7906108308882;1074.2158224496122;1082.9603580562668;1083.1118482844072;1085.1535802074354;1097.5225776787083;1150.4822829726616;1150.7180740718536;1150.7583407484437;1151.0216316131264;1131.4964840645957;1132.7224829916722;1134.3420815926743;1142.8571428571388;1143.0112952296204;814.8044469327318;815.6900377856376;815.963281652327;816.7263966571206;819.4427789103407;20467.590648187102;20895;21110;21143.259985900575;21232.575348492945;22204.55590888173;22419.663705044437;22672.659910101363;22707.20524781641;22750.54498910029;17214.82785172145;17326.306947722074;17515.912420437817;17531.824681753147;17702.822971770376;3948.032731980683;3978.8607398740905;3978.900527486807;3980.2835489611857;3985.60143985602;131033;131895.34052329772;132804;133187;133587.33206333907;17615.471535853943;17972.1013949302;17975.550611234823;18215.817841821412;18228.63542729138;31015.844920775253;31658.841705791325;32548.83725581357;32737.836310818293;32737.836310818293;8516.27611653004;9405.341626086245;9784.412935223909;9978.301518893608;10019.248556358303;4522.389477420572;4689.062187562433;4690.554397332248;4702.52974702527;4708.105459962596;5877.882442351129;5960.493358064527;5965.015772397506;6006.33930267679;6031.909521357183;1223.4188760338768;1238.6779437346174;1257.1891130220956;1259.9496020159274;1262.236347010064;736.3887972982412;750.8122969257686;752.7591170825393;772.4901564967095;777.4868586732729;11921.34432606215;12144.392780360946;12305.138640295272;12503.874961250453;12634.494620215271;750.8761054426068;751.6392131776778;758.4425447296233;762.5310434082999;763.2901401696475;5843.970780146072;6163.445289923929;6227.813165605039;6269.811905642838;6269.937300627026;129491;131422;131832.3408382952;133796.33101834625;134365.32817335852;114485;118905;121026;121323.39338303253;121730;101538.49230753799;102946;103633.48183259036;106270;106996.46501767442;12431.502739890304;12911.096223264281;13257.734845303234;13318.800217996932;13367.799483007568;50175.74912125416;50439;51425;51937.480625194774;52420;84748.57625712079;86878;88523;88544;92837.53581232051;16776.66446671084;19150.659453838096;19302.61394772125;19431.514212144244;19483.512912176742;19612.8038719611;19629.018549072774;19828.107735151665;20519.69220461724;21457.777244598892;18650;18737.812621873607;18927.71608425847;18955.147018384483;19150.233990640776;18988.335408260646;19345.322913697953;20310.796892030892;20687.379378618665;20739.585208296052;17888.821111789235;18237.361692340703;19898.60202795907;20081.196752129752;20173.092210850842;13933.23367214813;14006.719865602834;14246.216458094905;14402.711945760817;14536.5639030825;14651.267436627913;14917.47788827386;15090.773638395653;15226.619334516725;15324.540263792103 16:37:39 INFO - PID 8163 | __end_tp_report 16:37:39 INFO - PID 8163 | __start_cc_report 16:37:39 INFO - PID 8163 | _x_x_mozilla_cycle_collect,358 16:37:39 INFO - PID 8163 | __end_cc_report 16:37:39 INFO - PID 8163 | __startTimestamp1504395459615__endTimestamp 16:37:39 INFO - PID 8163 | 16:37:39 INFO - PID 8163 | ------- Summary: start ------- 16:37:39 INFO - PID 8163 | Number of tests: 6 16:37:39 INFO - PID 8163 | 16:37:39 INFO - PID 8163 | [#0] dojo.html Cycles:165 Average:6799.66 Median:7054.15 stddev:734.73 (10.4%) stddev-sans-first:700.04 16:37:39 INFO - PID 8163 | Values: 3866.9 3885.8 3904.6 3915.3 3943.1 6723.1 6796.3 6817.0 6819.5 6838.0 6739.1 6817.4 6824.1 6834.9 6835.3 6124.3 6136.6 6164.4 6179.5 6185.8 6085.8 6112.0 6126.8 6132.9 6175.5 6152.3 6168.4 6172.2 6184.8 6231.4 6045.4 6077.7 6106.3 6133.6 6176.8 5946.7 5990.3 5997.2 6043.2 6050.1 6765.9 6829.0 6835.4 6840.6 6922.4 7311.2 7321.0 7386.6 7423.3 7470.6 7387.6 7424.9 7428.8 7494.9 7519.3 6904.6 6915.9 6930.8 6934.1 6947.6 7514.8 7538.4 7576.4 7609.9 7643.9 7238.5 7264.5 7332.7 7356.4 7358.8 7296.6 7324.0 7363.8 7375.0 7391.9 7305.7 7353.6 7371.6 7387.2 7406.3 7307.3 7312.8 7315.8 7436.0 7446.6 6264.8 6305.8 6370.0 6394.9 6411.1 5451.6 5500.3 5544.9 5559.2 5562.1 6296.4 6306.9 6349.0 6370.2 6420.6 7310.8 7311.5 7390.2 7419.3 7426.6 6382.2 6398.3 6410.8 6411.7 6441.5 6649.9 6672.7 6673.1 6754.9 6762.3 7151.1 7155.9 7224.5 7232.1 7255.1 7245.1 7396.2 7409.4 7448.3 7459.5 7173.1 7173.9 7269.7 7270.6 7273.8 7189.7 7265.0 7276.2 7301.2 7369.4 7330.2 7388.0 7427.4 7444.7 7446.4 7043.4 7110.6 7149.6 7163.6 7172.1 7039.0 7054.2 7071.6 7151.6 7155.3 7055.6 7073.9 7124.7 7132.8 7134.0 7123.4 7155.8 7181.5 7258.9 7275.9 6904.5 6989.8 7002.2 7011.0 7026.0 16:37:39 INFO - PID 8163 | 16:37:39 INFO - PID 8163 | [#1] ext.html Cycles:180 Average:40868.97 Median:3718.93 stddev:60827.60 (1635.6%) stddev-sans-first:60924.01 16:37:39 INFO - PID 8163 | Values: 846.6 900.5 913.2 940.8 1241.5 32454.8 32913.5 40847.4 40929.6 41203.6 42202.4 42434.6 42478.6 42519.8 42574.8 7294.8 7843.4 8146.6 8147.9 8151.7 7674.3 7741.3 7744.2 7745.5 7763.3 7710.5 7737.0 7761.1 7794.4 7798.6 7701.7 7716.5 7720.7 7726.2 7733.7 7794.7 7805.2 7827.2 7828.5 7836.7 37461.6 37507.2 37562.0 37574.0 38619.4 3703.5 3707.8 3730.1 3746.2 3767.6 32294.7 32497.0 32500.5 32501.3 32841.7 5309.7 5438.6 5445.3 5457.6 5499.1 212903.0 213398.0 214302.0 217070.0 220001.0 50592.7 50942.0 51016.5 51047.5 51081.0 53663.7 53742.0 53767.0 53819.7 53830.0 13401.1 15144.7 15272.4 15278.1 15280.2 10870.0 11339.3 11439.1 11466.8 11473.1 14662.7 14684.3 14733.5 14759.4 14860.3 5646.6 5755.0 5817.7 5824.9 5850.4 1081.2 1083.6 1085.3 1085.4 1088.7 25004.0 25400.5 25420.4 25501.6 25537.5 1167.8 1175.3 1179.5 1182.1 1193.0 13291.9 13309.5 13310.9 13393.3 13581.0 264598.0 264952.0 265223.0 265456.0 265572.0 55543.4 55631.7 56122.0 56214.2 56302.4 1489.3 1501.4 1522.2 1522.9 1527.6 24789.5 24806.3 24867.8 24903.8 25011.5 39256.4 39261.0 39281.8 39303.6 39312.6 217603.0 218033.0 218676.0 218968.0 219106.0 36115.8 38243.0 38591.2 38629.8 38707.0 45737.3 47695.3 47734.5 48197.3 48362.0 35296.6 35686.5 35876.6 35961.0 36091.1 34924.0 35018.6 35082.5 35281.6 35596.3 40289.2 41328.0 41664.4 41671.7 42017.6 33836.0 33854.3 33862.8 34345.5 34627.1 25973.9 26316.2 26375.0 26717.4 27228.9 16:37:39 INFO - PID 8163 | 16:37:39 INFO - PID 8163 | [#2] jquery.html Cycles:180 Average:39451.87 Median:23090.21 stddev:99474.94 (430.8%) stddev-sans-first:99711.19 16:37:39 INFO - PID 8163 | Values: 584.4 585.8 591.6 598.4 606.7 23009.8 23170.7 23991.8 24016.5 24036.2 23352.9 23861.5 23865.9 23895.6 23924.5 6730.6 6868.8 6876.2 6888.9 6892.8 6560.6 6601.4 6661.3 6775.3 6808.1 6763.4 6777.7 6778.4 6778.5 6780.1 6729.5 6753.7 6765.1 6771.4 6775.4 6300.7 6308.2 6309.0 6315.5 6334.7 29985.7 30789.5 31357.8 31650.8 31669.2 2200.4 2240.0 2242.0 2242.9 2243.1 20614.7 21163.7 21174.8 21188.8 21197.9 2400.4 2433.2 2493.3 2497.8 2550.9 149508.0 151399.0 151905.0 152364.2 152522.2 15389.4 15946.0 16050.8 16219.2 16685.7 25416.0 25417.0 25435.2 25446.4 25547.5 10027.4 10045.2 10087.5 10208.4 10229.7 8227.3 8959.9 8991.9 9114.1 9187.7 8820.5 9621.4 9686.6 9701.2 10095.2 2763.2 2765.8 2792.3 2875.5 2978.3 1055.6 1066.0 1068.9 1089.2 1092.0 21819.5 22288.9 22507.8 22530.7 22567.1 1121.9 1125.0 1129.8 1134.4 1142.0 11773.6 12222.8 12391.3 12450.6 12463.9 577702.0 589220.0 594532.0 597766.0 610081.0 125888.4 129991.0 130039.0 130942.0 132005.0 1205.9 1242.4 1280.3 1283.7 1292.6 19170.1 19358.4 19677.0 19778.2 19931.3 58160.0 60575.0 61120.4 62718.7 63552.7 77571.6 78278.2 78922.0 79445.0 79882.6 12685.2 13118.8 14676.3 15093.8 15457.3 24611.3 24704.0 24734.6 25029.5 25072.1 19878.5 20199.8 20228.0 20362.6 20388.5 17534.3 17730.6 17827.0 17885.3 17966.2 18763.5 18891.8 18956.2 19051.8 19156.3 15678.7 15706.0 15722.8 23009.3 23560.9 21101.0 21335.5 21592.7 21598.6 21609.6 16:37:39 INFO - PID 8163 | 16:37:39 INFO - PID 8163 | [#3] mootools.html Cycles:180 Average:10361.01 Median:2686.97 stddev:7792.33 (290.0%) stddev-sans-first:7785.21 16:37:39 INFO - PID 8163 | Values: 1416.6 1453.4 1464.9 1481.6 1488.3 16753.8 17008.0 17175.1 17419.3 17436.7 17230.1 17520.3 17520.6 17526.6 17544.3 2870.6 2930.3 2932.1 2934.3 2934.7 2577.7 2630.3 2642.5 2658.9 2842.7 2708.3 2750.3 2756.6 2758.8 2759.8 2642.8 2652.1 2747.4 2752.1 2819.4 2682.3 2691.6 2705.6 2720.3 2729.8 15988.0 16241.3 16340.0 16345.2 16588.7 8585.4 8866.9 8868.8 8870.8 8871.0 5479.8 5589.7 5661.4 5674.7 5741.3 2033.5 2059.6 2062.7 2081.0 2096.5 30094.2 30302.4 30373.4 30444.7 30618.2 7233.0 7236.7 7266.5 7453.8 7459.7 19004.0 19138.5 19149.7 19158.6 19168.6 8439.2 8613.4 8624.4 8624.7 8667.9 7783.8 7892.2 7947.2 7991.9 8007.6 5552.9 5744.5 5748.5 5774.5 5792.5 1646.7 1672.3 1687.1 1688.9 1714.7 927.9 948.7 955.3 965.1 966.3 8099.4 8099.5 8105.2 8144.1 8191.1 785.3 795.7 796.0 797.4 809.2 5586.7 5609.6 5677.3 5714.5 5924.8 24200.2 25233.2 25426.6 25783.5 25994.0 24932.6 25523.2 25627.2 25740.7 25811.1 10555.9 11886.8 12891.5 13092.7 13125.1 9741.7 10184.0 10299.6 10345.0 10481.8 21054.7 21438.0 21873.9 22098.3 22543.4 21707.3 22055.4 22176.2 22201.8 22237.4 13504.3 13775.2 13815.7 13847.6 13853.6 15093.3 15126.7 15173.5 15199.3 15326.0 11469.9 11500.7 11529.2 11707.2 11781.9 11142.2 11246.9 11299.2 11543.8 11728.1 9296.3 9519.2 9534.8 9546.1 9552.7 7617.0 7680.5 7686.2 7696.5 7751.5 7551.1 7581.2 7603.4 7734.4 7770.7 16:37:39 INFO - PID 8163 | 16:37:39 INFO - PID 8163 | [#4] prototype.html Cycles:180 Average:5948.41 Median:6629.87 stddev:1682.69 (25.4%) stddev-sans-first:1637.52 16:37:39 INFO - PID 8163 | Values: 529.8 546.5 559.8 567.9 575.7 6773.0 6778.7 6794.9 6811.4 6827.6 6428.4 6561.8 6669.4 6684.7 6699.2 5282.1 5300.1 5337.1 5343.6 5346.1 5294.0 5306.9 5339.1 5346.4 5352.9 5085.6 5248.5 5266.1 5272.3 5277.5 5211.3 5241.7 5257.5 5270.3 5281.5 4699.1 4718.0 4721.9 4724.2 4741.0 6549.3 6611.1 6627.2 6632.5 6717.8 6203.6 6222.4 6233.5 6259.3 6266.0 7001.3 7100.3 7101.5 7105.2 7156.7 5253.3 5258.0 5266.8 5298.7 5308.0 7507.7 7551.2 7575.3 7584.6 7588.5 5419.4 5427.0 5441.0 5480.7 5504.4 5489.5 5507.5 5532.9 5537.1 5538.0 6114.2 6232.1 6233.4 6245.6 6250.4 7106.8 7121.9 7163.1 7232.2 7239.3 1819.9 1860.7 1878.0 1891.1 1914.4 1274.6 1330.3 1337.0 1337.1 1346.5 5493.0 6145.8 6237.1 6237.5 6246.1 7045.8 7073.9 7132.9 7141.3 7244.7 6177.2 6196.3 6200.8 6207.4 6296.0 3808.5 3829.8 3836.3 3849.3 3934.7 6925.3 7084.8 7104.2 7138.4 7138.8 7305.4 7391.7 7438.2 7506.0 7614.9 7074.4 7085.9 7106.0 7131.6 7154.2 7190.3 7201.3 7205.4 7285.5 7302.0 7163.4 7205.9 7268.9 7300.2 7378.3 6961.0 6963.2 7006.4 7049.4 7150.7 5343.7 5368.1 5421.2 5435.5 5451.8 7317.0 7319.5 7375.3 7402.7 7408.3 6953.3 6974.4 7008.7 7045.5 7073.5 6996.0 7026.2 7026.9 7044.6 7062.9 6911.3 6965.7 6990.0 6995.8 7057.5 6368.8 7045.7 7059.6 7120.8 7131.8 6749.4 6754.3 6766.7 6781.2 6934.3 16:37:39 INFO - PID 8163 | 16:37:39 INFO - PID 8163 | [#5] yui.html Cycles:180 Average:26972.07 Median:19388.42 stddev:37647.54 (194.2%) stddev-sans-first:37702.45 16:37:39 INFO - PID 8163 | Values: 952.3 976.2 996.2 1009.6 1010.4 19297.0 19819.0 19861.8 19872.6 23514.9 20110.8 20114.8 20333.6 20336.5 20348.2 1053.8 1069.5 1069.6 1069.6 1073.5 1073.8 1074.2 1083.0 1083.1 1085.2 1097.5 1150.5 1150.7 1150.8 1151.0 1131.5 1132.7 1134.3 1142.9 1143.0 814.8 815.7 816.0 816.7 819.4 20467.6 20895.0 21110.0 21143.3 21232.6 22204.6 22419.7 22672.7 22707.2 22750.5 17214.8 17326.3 17515.9 17531.8 17702.8 3948.0 3978.9 3978.9 3980.3 3985.6 131033.0 131895.3 132804.0 133187.0 133587.3 17615.5 17972.1 17975.6 18215.8 18228.6 31015.8 31658.8 32548.8 32737.8 32737.8 8516.3 9405.3 9784.4 9978.3 10019.2 4522.4 4689.1 4690.6 4702.5 4708.1 5877.9 5960.5 5965.0 6006.3 6031.9 1223.4 1238.7 1257.2 1259.9 1262.2 736.4 750.8 752.8 772.5 777.5 11921.3 12144.4 12305.1 12503.9 12634.5 750.9 751.6 758.4 762.5 763.3 5844.0 6163.4 6227.8 6269.8 6269.9 129491.0 131422.0 131832.3 133796.3 134365.3 114485.0 118905.0 121026.0 121323.4 121730.0 101538.5 102946.0 103633.5 106270.0 106996.5 12431.5 12911.1 13257.7 13318.8 13367.8 50175.7 50439.0 51425.0 51937.5 52420.0 84748.6 86878.0 88523.0 88544.0 92837.5 16776.7 19150.7 19302.6 19431.5 19483.5 19612.8 19629.0 19828.1 20519.7 21457.8 18650.0 18737.8 18927.7 18955.1 19150.2 18988.3 19345.3 20310.8 20687.4 20739.6 17888.8 18237.4 19898.6 20081.2 20173.1 13933.2 14006.7 14246.2 14402.7 14536.6 14651.3 14917.5 15090.8 15226.6 15324.5 16:37:39 INFO - PID 8163 | -------- Summary: end -------- 16:37:39 INFO - PID 8163 | 16:37:40 INFO - TEST-INFO | 8163: exit 0 16:37:40 INFO - TEST-OK | dromaeo_css | took 1110850ms 16:37:40 INFO - TEST-START | kraken 16:37:40 INFO - Initialising browser for kraken test... 16:37:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53172/getInfo.html -profile /tmp/tmpwFtNx9/profile 16:37:40 INFO - TEST-INFO | started process 8539 (/builds/slave/test/build/application/firefox/firefox http://localhost:53172/getInfo.html) 16:37:45 INFO - TEST-INFO | 8539: exit 0 16:37:45 INFO - Browser initialized. 16:37:45 INFO - Running cycle 1/1 for kraken test... 16:37:45 INFO - Using env: {'DISPLAY': ':0', 16:37:45 INFO - 'HOME': '/home/cltbld', 16:37:45 INFO - 'JSGC_DISABLE_POISONING': '1', 16:37:45 INFO - 'LANG': 'en_US.UTF-8', 16:37:45 INFO - 'LANGUAGE': 'en_US:en', 16:37:45 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:37:45 INFO - 'LOGNAME': 'cltbld', 16:37:45 INFO - 'MAIL': '/var/mail/cltbld', 16:37:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:37:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:37:45 INFO - 'MOZ_CRASHREPORTER': '1', 16:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:45 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:37:45 INFO - 'MOZ_NO_REMOTE': '1', 16:37:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:37:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:45 INFO - 'NO_EM_RESTART': '1', 16:37:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:45 INFO - 'PWD': '/builds/slave/test', 16:37:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:37:45 INFO - 'RUST_BACKTRACE': 'full', 16:37:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:37:45 INFO - 'SHELL': '/bin/bash', 16:37:45 INFO - 'SHLVL': '1', 16:37:45 INFO - 'TERM': 'linux', 16:37:45 INFO - 'TMOUT': '86400', 16:37:45 INFO - 'USER': 'cltbld', 16:37:45 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260', 16:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:45 INFO - '_': '/tools/buildbot/bin/python'} 16:37:45 INFO - TEST-INFO | started process 8728 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwFtNx9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:37:46 INFO - PID 8728 | 16:37:46 INFO - PID 8728 | (/builds/slave/test/build/application/firefox/firefox:8780): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:37:46 INFO - PID 8728 | 16:37:56 INFO - PID 8728 | RSS: Main: 163323904 16:37:56 INFO - PID 8728 | 16:37:59 INFO - PID 8728 | Cycle 1(1): loaded http://localhost:53172/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53172/tests/kraken/driver.html?testName=audio-beat-detection) 16:38:00 INFO - PID 8728 | RSS: Main: 167895040 16:38:00 INFO - PID 8728 | 16:38:02 INFO - PID 8728 | Cycle 1(1): loaded http://localhost:53172/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53172/tests/kraken/driver.html?testName=audio-dft) 16:38:03 INFO - PID 8728 | RSS: Main: 172384256 16:38:03 INFO - PID 8728 | 16:38:06 INFO - PID 8728 | Cycle 1(1): loaded http://localhost:53172/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53172/tests/kraken/driver.html?testName=audio-fft) 16:38:07 INFO - PID 8728 | RSS: Main: 165081088 16:38:07 INFO - PID 8728 | 16:38:09 INFO - PID 8728 | Cycle 1(1): loaded http://localhost:53172/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53172/tests/kraken/driver.html?testName=audio-oscillator) 16:38:09 INFO - PID 8728 | RSS: Main: 166514688 16:38:09 INFO - PID 8728 | 16:38:11 INFO - PID 8728 | Cycle 1(1): loaded http://localhost:53172/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53172/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:38:12 INFO - PID 8728 | RSS: Main: 165814272 16:38:12 INFO - PID 8728 | 16:38:15 INFO - PID 8728 | Cycle 1(1): loaded http://localhost:53172/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53172/tests/kraken/driver.html?testName=imaging-darkroom) 16:38:16 INFO - PID 8728 | RSS: Main: 165974016 16:38:16 INFO - PID 8728 | 16:38:19 INFO - PID 8728 | Cycle 1(1): loaded http://localhost:53172/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53172/tests/kraken/driver.html?testName=imaging-desaturate) 16:38:20 INFO - PID 8728 | RSS: Main: 167985152 16:38:20 INFO - PID 8728 | 16:38:22 INFO - PID 8728 | Cycle 1(1): loaded http://localhost:53172/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53172/tests/kraken/driver.html?testName=json-parse-financial) 16:38:23 INFO - PID 8728 | RSS: Main: 168316928 16:38:23 INFO - PID 8728 | 16:38:24 INFO - PID 8728 | Cycle 1(1): loaded http://localhost:53172/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53172/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:38:25 INFO - PID 8728 | RSS: Main: 167702528 16:38:25 INFO - PID 8728 | 16:38:27 INFO - PID 8728 | Cycle 1(1): loaded http://localhost:53172/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53172/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:38:27 INFO - PID 8728 | RSS: Main: 167821312 16:38:27 INFO - PID 8728 | 16:38:29 INFO - PID 8728 | Cycle 1(1): loaded http://localhost:53172/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53172/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:38:30 INFO - PID 8728 | RSS: Main: 164499456 16:38:30 INFO - PID 8728 | 16:38:32 INFO - PID 8728 | Cycle 1(1): loaded http://localhost:53172/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53172/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:38:33 INFO - PID 8728 | RSS: Main: 164712448 16:38:33 INFO - PID 8728 | 16:38:36 INFO - PID 8728 | Cycle 1(1): loaded http://localhost:53172/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53172/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:38:37 INFO - PID 8728 | RSS: Main: 164921344 16:38:37 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | Cycle 1(1): loaded http://localhost:53172/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53172/tests/kraken/driver.html?testName=ai-astar) 16:38:38 INFO - PID 8728 | RSS: Main: 166912000 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | __start_tp_report 16:38:38 INFO - PID 8728 | _x_x_mozilla_page_load 16:38:38 INFO - PID 8728 | _x_x_mozilla_page_load_details 16:38:38 INFO - PID 8728 | |i|pagename|runs| 16:38:38 INFO - PID 8728 | |0;ai-astar;96;106;99;106;105;105;107;96;105;109 16:38:38 INFO - PID 8728 | |1;audio-beat-detection;142;114;140;163;115;143;153;112;186;114 16:38:38 INFO - PID 8728 | |2;audio-dft;163;163;162;161;162;162;164;162;161;170 16:38:38 INFO - PID 8728 | |3;audio-fft;74;73;73;172;74;73;73;73;152;75 16:38:38 INFO - PID 8728 | |4;audio-oscillator;78;78;126;77;77;77;126;78;78;129 16:38:38 INFO - PID 8728 | |5;imaging-gaussian-blur;115;114;114;114;114;114;114;114;115;115 16:38:38 INFO - PID 8728 | |6;imaging-darkroom;150;150;150;151;150;150;150;150;150;150 16:38:38 INFO - PID 8728 | |7;imaging-desaturate;94;93;95;93;94;94;94;94;94;94 16:38:38 INFO - PID 8728 | |8;json-parse-financial;44;43;47;45;50;43;44;53;44;44 16:38:38 INFO - PID 8728 | |9;json-stringify-tinderbox;39;39;39;39;38;39;38;40;39;38 16:38:38 INFO - PID 8728 | |10;stanford-crypto-aes;72;73;72;72;72;73;77;72;72;76 16:38:38 INFO - PID 8728 | |11;stanford-crypto-ccm;132;124;120;119;108;119;112;108;116;148 16:38:38 INFO - PID 8728 | |12;stanford-crypto-pbkdf2;159;156;159;152;155;155;155;153;164;161 16:38:38 INFO - PID 8728 | |13;stanford-crypto-sha256-iterative;61;63;60;60;60;60;59;61;60;58 16:38:38 INFO - PID 8728 | __end_tp_report 16:38:38 INFO - PID 8728 | __start_cc_report 16:38:38 INFO - PID 8728 | _x_x_mozilla_cycle_collect,919 16:38:38 INFO - PID 8728 | __end_cc_report 16:38:38 INFO - PID 8728 | __startTimestamp1504395518953__endTimestamp 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | ------- Summary: start ------- 16:38:38 INFO - PID 8728 | Number of tests: 14 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | [#0] ai-astar Cycles:10 Average:103.40 Median:108.00 stddev:4.65 (4.3%) stddev-sans-first:4.09 16:38:38 INFO - PID 8728 | Values: 96.0 106.0 99.0 106.0 105.0 105.0 107.0 96.0 105.0 109.0 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:142.50 stddev:24.85 (17.4%) stddev-sans-first:26.31 16:38:38 INFO - PID 8728 | Values: 142.0 114.0 140.0 163.0 115.0 143.0 153.0 112.0 186.0 114.0 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | [#2] audio-dft Cycles:10 Average:163.00 Median:162.50 stddev:2.62 (1.6%) stddev-sans-first:2.78 16:38:38 INFO - PID 8728 | Values: 163.0 163.0 162.0 161.0 162.0 162.0 164.0 162.0 161.0 170.0 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | [#3] audio-fft Cycles:10 Average:91.20 Median:73.00 stddev:37.62 (51.5%) stddev-sans-first:39.38 16:38:38 INFO - PID 8728 | Values: 74.0 73.0 73.0 172.0 74.0 73.0 73.0 73.0 152.0 75.0 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | [#4] audio-oscillator Cycles:10 Average:92.40 Median:77.50 stddev:23.89 (30.8%) stddev-sans-first:24.77 16:38:38 INFO - PID 8728 | Values: 78.0 78.0 126.0 77.0 77.0 77.0 126.0 78.0 78.0 129.0 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 16:38:38 INFO - PID 8728 | Values: 115.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 115.0 115.0 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | [#6] imaging-darkroom Cycles:10 Average:150.10 Median:150.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 16:38:38 INFO - PID 8728 | Values: 150.0 150.0 150.0 151.0 150.0 150.0 150.0 150.0 150.0 150.0 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:38:38 INFO - PID 8728 | Values: 94.0 93.0 95.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | [#8] json-parse-financial Cycles:10 Average:45.70 Median:44.50 stddev:3.33 (7.5%) stddev-sans-first:3.48 16:38:38 INFO - PID 8728 | Values: 44.0 43.0 47.0 45.0 50.0 43.0 44.0 53.0 44.0 44.0 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | [#9] json-stringify-tinderbox Cycles:10 Average:38.80 Median:39.00 stddev:0.63 (1.6%) stddev-sans-first:0.67 16:38:38 INFO - PID 8728 | Values: 39.0 39.0 39.0 39.0 38.0 39.0 38.0 40.0 39.0 38.0 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | [#10] stanford-crypto-aes Cycles:10 Average:73.10 Median:72.50 stddev:1.85 (2.6%) stddev-sans-first:1.92 16:38:38 INFO - PID 8728 | Values: 72.0 73.0 72.0 72.0 72.0 73.0 77.0 72.0 72.0 76.0 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | [#11] stanford-crypto-ccm Cycles:10 Average:120.60 Median:119.50 stddev:12.07 (10.1%) stddev-sans-first:12.07 16:38:38 INFO - PID 8728 | Values: 132.0 124.0 120.0 119.0 108.0 119.0 112.0 108.0 116.0 148.0 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.90 Median:157.50 stddev:3.75 (2.4%) stddev-sans-first:3.91 16:38:38 INFO - PID 8728 | Values: 159.0 156.0 159.0 152.0 155.0 155.0 155.0 153.0 164.0 161.0 16:38:38 INFO - PID 8728 | 16:38:38 INFO - PID 8728 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.20 Median:60.00 stddev:1.32 (2.2%) stddev-sans-first:1.36 16:38:38 INFO - PID 8728 | Values: 61.0 63.0 60.0 60.0 60.0 60.0 59.0 61.0 60.0 58.0 16:38:38 INFO - PID 8728 | -------- Summary: end -------- 16:38:38 INFO - PID 8728 | 16:38:39 INFO - PID 8728 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:38:39 INFO - TEST-INFO | 8728: exit 0 16:38:39 INFO - TEST-OK | kraken | took 59335ms 16:38:39 INFO - SUITE-END | took 1170s 16:38:40 INFO - Completed test suite (00:19:30) 16:38:40 INFO - javascript benchmark 16:38:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9213.890210091597, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3866.883993480196, 3885.8251378687974, 3904.609539046096, 3915.295246855565, 3943.0733777562286, 6723.092382528357, 6796.320367963207, 6816.965915170424, 6819.4544436445085, 6838.042674025634, 6739.0902228199175, 6817.386435220829, 6824.146981627297, 6834.863302733942, 6835.316468353175, 6124.2957059938235, 6136.631802091878, 6164.383561643822, 6179.536534759911, 6185.752569897199, 6085.847853803668, 6112.0, 6126.7549298028025, 6132.938670613304, 6175.505959523227, 6152.323244443108, 6168.4448399644025, 6172.166757487749, 6184.752609895621, 6231.439170474657, 6045.395460453963, 6077.726502307385, 6106.267247930276, 6133.6319820810895, 6176.8455788605415, 5946.672932988684, 5990.281166260077, 5997.190379298826, 6043.153958445821, 6050.062240352752, 6765.8646827063185, 6829.009793579903, 6835.418989385908, 6840.589564626139, 6922.446204303644, 7311.1592166900955, 7321.0, 7386.556806591568, 7423.294786995227, 7470.58911759853, 7387.556746595222, 7424.888626670604, 7428.814279642998, 7494.887576686299, 7519.32326090649, 6904.551204171713, 6915.89626155603, 6930.826729331757, 6934.133233345832, 6947.617881016542, 7514.774556763306, 7538.3592394645975, 7576.4317676174505, 7609.923900760921, 7643.923560764321, 7238.52949558277, 7264.455165862476, 7332.670029848563, 7356.411487081021, 7358.77923662291, 7296.629258277379, 7323.963380183065, 7363.815904602321, 7374.963125184339, 7391.85216295671, 7305.700509930783, 7353.558786472829, 7371.636247294267, 7387.150477695026, 7406.296401841924, 7307.3058059484265, 7312.817179570553, 7315.841826661148, 7436.0, 7446.553206807609, 6264.812055638338, 6305.8108256752375, 6369.9681501592195, 6394.872102558016, 6411.070394792729, 5451.618386712892, 5500.312460942382, 5544.944550554524, 5559.19391688211, 5562.082256427725, 6296.401841825019, 6306.936930630635, 6349.0476428536085, 6370.235571731422, 6420.6147631142285, 7310.811890682469, 7311.524750891175, 7390.22402647725, 7419.258074192538, 7426.554406735613, 6382.234131904202, 6398.328175541595, 6410.839729006812, 6411.743530258737, 6441.516886233551, 6649.933500664932, 6672.699728512229, 6673.065770792093, 6754.898676519856, 6762.256151823295, 7151.070360853056, 7155.89266161008, 7224.4581656375985, 7232.059832221883, 7255.129384473897, 7245.1290218230615, 7396.186419493743, 7409.407247420086, 7448.3296503312795, 7459.477836551389, 7173.1392232932385, 7173.856522869409, 7269.709211631687, 7270.636468176464, 7273.818154546178, 7189.712411503481, 7264.963675181591, 7276.163241227327, 7301.160366557914, 7369.410447164107, 7330.193678695233, 7387.963060184665, 7427.368673662799, 7444.664990075568, 7446.441516886147, 7043.436525077879, 7110.57336559808, 7149.642517873982, 7163.64181790898, 7172.0676312079095, 7039.0, 7054.153501579844, 7071.575705457689, 7151.570905745672, 7155.284471552803, 7055.576665400093, 7073.929260707327, 7124.679389427384, 7132.750353737802, 7134.036905017963, 7123.394511466583, 7155.821104472429, 7181.461390395846, 7258.854822903618, 7275.8908616371855, 6904.482163837833, 6989.7553585624255, 7002.1947476040905, 7010.964945175241, 7026.0], "value": 6752.192275914784, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [846.5894041389924, 900.4822227219348, 913.1507697841009, 940.7930255343822, 1241.5344245907784, 32454.83772581137, 32913.506297405525, 40847.38728919065, 40929.59070409295, 41203.58796412035, 42202.36696449556, 42434.57565424345, 42478.575214247845, 42519.787401063026, 42574.78712606433, 7294.781156565312, 7843.411744119186, 8146.633401496947, 8147.918520814805, 8151.714689985852, 7674.309312161904, 7741.341985931174, 7744.225577442267, 7745.457817952745, 7763.262490063435, 7710.498817576868, 7737.0, 7761.107472640634, 7794.3764498839955, 7798.571078590675, 7701.730439434621, 7716.5370077795515, 7720.729774457923, 7726.188750181206, 7733.651985660631, 7794.688212471494, 7805.1804560520895, 7827.178146294614, 7828.530288182727, 7836.725714599962, 37461.62538374608, 37507.24985500302, 37562.0, 37574.0, 38619.42070868911, 3703.5370578677666, 3707.7775333480076, 3730.08612889841, 3746.1571146492092, 3767.6420740029926, 32294.677053229403, 32497.0, 32500.512492312635, 32501.349973000408, 32841.6715832841, 5309.654872433318, 5438.646487978269, 5445.264889239941, 5457.5633949283965, 5499.065158922993, 212903.0, 213397.99999999846, 214302.0, 217070.0, 220001.0, 50592.71147375409, 50942.0, 51016.489835101915, 51047.489525104276, 51081.0, 53663.73168134134, 53742.0, 53767.0, 53819.73090134524, 53830.0, 13401.128926619738, 15144.697106057818, 15272.38910443592, 15278.083315001135, 15280.235988200546, 10870.0, 11339.319640821577, 11439.142064345207, 11466.77066458683, 11473.082153427873, 14662.706745865024, 14684.26578671062, 14733.484328048467, 14759.409623615149, 14860.331285092198, 5646.632968857011, 5755.0, 5817.680027598522, 5824.854378640568, 5850.3564607893095, 1081.1999120650662, 1083.5774048121239, 1085.2999815119204, 1085.365061439067, 1088.711491454765, 25004.0, 25400.49199016009, 25420.364490887874, 25501.61747573788, 25537.48925021489, 1167.8088349883071, 1175.3124422213102, 1179.5163982767024, 1182.0720734223594, 1193.0093836118172, 13291.93354033243, 13309.46762129504, 13310.866891330963, 13393.330333483287, 13581.049326547047, 264598.0, 264952.0, 265223.0, 265456.0, 265572.0, 55543.44456555383, 55631.72184139134, 56122.0, 56214.156787648215, 56302.43697563054, 1489.255372313843, 1501.3544176004423, 1522.1704171226709, 1522.9086254824747, 1527.6486408126193, 24789.50420991534, 24806.263561550517, 24867.751322486543, 24903.750962490867, 25011.499770004863, 39256.411153832145, 39261.0, 39281.803590981865, 39303.60696393, 39312.60687393204, 217603.0, 218033.0, 218676.0, 218968.0, 219106.0, 36115.819420903776, 38243.0, 38591.228175436896, 38629.806850965564, 38707.0, 45737.31394029159, 47695.28457073077, 47734.522654774395, 48197.27704084371, 48362.0, 35296.647033529334, 35686.464703028956, 35876.64123358837, 35961.0, 36091.09772255715, 34924.0, 35018.649813501535, 35082.47376289307, 35281.647183527835, 35596.28807423889, 40289.19421611493, 41328.0, 41664.3750343739, 41671.74984750462, 42017.57982420137, 33836.0, 33854.322913542084, 33862.83068584641, 34345.48481772726, 34627.13432164216, 25973.870130649226, 26316.21051368462, 26375.0, 26717.396956182693, 27228.863855680596], "value": 18036.572923301577, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.3718003146619, 585.7744768264217, 591.6213623281101, 598.4314900844199, 606.7481994972086, 23009.769902300974, 23170.65244021341, 23991.76008239917, 24016.51966960662, 24036.1587344443, 23352.883235583842, 23861.522769544597, 23865.880670596667, 23895.64156537649, 23924.521509569797, 6730.59616423015, 6868.828279293008, 6876.209235937881, 6888.862222755542, 6892.827679308008, 6560.573562718432, 6601.438877695402, 6661.26726060133, 6775.288594697559, 6808.080909077261, 6763.357481039294, 6777.695003724844, 6778.423833974093, 6778.49161312901, 6780.08468856703, 6729.528932974669, 6753.729850805962, 6765.086713293692, 6771.356721111511, 6775.356341147583, 6300.747970081237, 6308.211473565804, 6308.968455157695, 6315.463185629181, 6334.683265836736, 29985.70014299851, 30789.538156927665, 31357.8432107838, 31650.841745791124, 31669.20826979321, 2200.4058904095964, 2240.014393666798, 2241.9663705044436, 2242.932712018626, 2243.069126312578, 20614.690779638317, 21163.68254476184, 21174.788252117436, 21188.78811211899, 21197.89401052985, 2400.3759022654235, 2433.221369161869, 2493.252024392693, 2497.7876880465183, 2550.9234722958154, 149508.0, 151399.0, 151905.0, 152364.2381788095, 152522.23738881346, 15389.384424623113, 15946.043237405793, 16050.75923861143, 16219.189040547926, 16685.666286674197, 25416.0, 25417.0, 25435.236942891373, 25446.363840904127, 25547.48905021889, 10027.398356098658, 10045.246606504541, 10087.546060427298, 10208.387496750218, 10229.74425639365, 8227.341812654973, 8959.864047583424, 8991.910080899239, 9114.088591140833, 9187.678431254873, 8820.514871681991, 9621.374610650286, 9686.612535498502, 9701.17540009093, 10095.192384609214, 2763.198672384986, 2765.7925655575914, 2792.3019245188702, 2875.5399136138117, 2978.3298757779758, 1055.5883205549842, 1066.0245875024266, 1068.9358638481715, 1089.1831126655009, 1091.9617813376494, 21819.454513636967, 22288.88855555744, 22507.774922250566, 22530.662040069412, 22567.0973161075, 1121.859767529059, 1124.9550017999188, 1129.8474705914762, 1134.3987686526154, 1142.0406858238953, 11773.646790596296, 12222.816657750142, 12391.256524608552, 12450.626481205578, 12463.937680311541, 577702.0, 589220.0, 594532.0, 597766.0, 610081.0, 125888.37055814662, 129991.0, 130039.0, 130942.0, 132005.0, 1205.8613259475273, 1242.3974372429363, 1280.308633337987, 1283.6919139406475, 1292.566990058341, 19170.13734381984, 19358.351273457734, 19677.0, 19778.208871644973, 19931.302404415776, 58160.0, 60575.0, 61120.38879611147, 62718.68640656768, 63552.682236588524, 77571.61214193892, 78278.2172178271, 78922.0, 79445.0, 79882.60058699669, 12685.175463594836, 13118.803217951547, 14676.266186690409, 15093.849061509245, 15457.304421301293, 24611.261662150166, 24704.0, 24734.628980565667, 25029.49941001206, 25072.122475713262, 19878.50303742418, 20199.79800202038, 20228.0, 20362.592748144656, 20388.490287742927, 17534.298628054734, 17730.64538709193, 17827.0, 17885.28458861631, 17966.191521381308, 18763.530911727317, 18891.811081889005, 18956.24175032983, 19051.80948190556, 19156.32952846644, 15678.686426271182, 15706.0, 15722.842771572137, 23009.309720708407, 23560.88219558891, 21101.0, 21335.46661333479, 21592.67610985805, 21598.568028639023, 21609.567808644053], "value": 12797.64444687842, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1416.5821082780578, 1453.4476898778469, 1464.905991077118, 1481.5925620454377, 1488.3228131200299, 16753.832461675378, 17007.979521228735, 17175.141242937865, 17419.303227870885, 17436.65126697465, 17230.138493075327, 17520.299188032495, 17520.561985950357, 17526.561835954108, 17544.29822807086, 2870.5550639650783, 2930.2527855396843, 2932.135020169055, 2934.251765799718, 2934.7065293470587, 2577.6520169777123, 2630.302969713028, 2642.484715480482, 2658.8670566471696, 2842.715728427151, 2708.3093811078247, 2750.2849259192553, 2756.641636587241, 2758.7517123458883, 2759.8344099354003, 2642.8150029497942, 2652.0717748787865, 2747.4230411613485, 2752.0918097028134, 2819.3797364579964, 2682.329417645589, 2691.6097165910833, 2705.60768688541, 2720.319920019995, 2729.7816174705977, 15988.0, 16241.2691428886, 16340.0, 16345.182740862909, 16588.66822663552, 8585.35609829259, 8866.86699699505, 8868.822623547558, 8870.822583548357, 8870.955645221797, 5479.780808767645, 5589.720513974285, 5661.433856614306, 5674.687892165929, 5741.33974592923, 2033.4509682385822, 2059.6395630764555, 2062.7009083682933, 2080.989595052015, 2096.4549217203485, 30094.24764380886, 30302.393952121056, 30373.392532149453, 30444.695553044407, 30618.23454413635, 7232.963835180791, 7236.710531578783, 7266.45501587383, 7453.776386708408, 7459.694553453124, 19004.0, 19138.52153696141, 19149.712754308697, 19158.61682766337, 19168.61662766737, 8439.240468357877, 8613.44012639189, 8624.353173512012, 8624.698135565224, 8667.869981950276, 7783.844323113506, 7892.210778922062, 7947.245011723877, 7991.920080799117, 8007.559584222807, 5552.944470555323, 5744.540436765133, 5748.45389687979, 5774.451427114417, 5792.478676919098, 1646.7118254305449, 1672.347784364099, 1687.1058339080307, 1688.9408870689467, 1714.7170716831583, 927.8654595083675, 948.7390967483997, 955.2787645327872, 965.1217392173229, 966.3042609321277, 8099.433039687283, 8099.514029158269, 8105.230003149692, 8144.104148543655, 8191.139930307351, 785.2618538573722, 795.7095660084028, 795.9602019898982, 797.4139007829241, 809.2271880354248, 5586.748596313176, 5609.635373700697, 5677.2619559457835, 5714.542836573064, 5924.785418989183, 24200.152994645105, 25233.243002709947, 25426.618600721005, 25783.484330313662, 25994.0, 24932.626010609856, 25523.23430297094, 25627.231183064538, 25740.742592573835, 25811.096611618505, 10555.894441055645, 11886.821697674543, 12891.548795792101, 13092.738145237043, 13125.146865453715, 9741.707748767549, 10184.0, 10299.639512617023, 10345.017223363768, 10481.842772358268, 21054.68417973701, 21438.0, 21873.890630546746, 22098.33704988853, 22543.436414089778, 21707.348779536638, 22055.448613784785, 22176.223832165797, 22201.777982220618, 22237.444063898532, 13504.324783760969, 13775.242361670204, 13815.723685526033, 13847.584572462842, 13853.584392468241, 15093.32080056378, 15126.697466050397, 15173.468928587446, 15199.316030778862, 15326.0, 11469.885301147215, 11500.654980350602, 11529.192956492965, 11707.239029463057, 11781.941090294493, 11142.220044596801, 11246.887531124585, 11299.152563557931, 11543.826842597537, 11728.12039097054, 9296.349255552046, 9519.190868776232, 9534.761630959005, 9546.093121153479, 9552.665656701982, 7616.961915190389, 7680.5391676499585, 7686.192949740197, 7696.538207707556, 7751.457397982087, 7551.131619863786, 7581.166071732216, 7603.353714934293, 7734.381249500139, 7770.728024519115], "value": 7187.0138418721335, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [529.8145649022842, 546.5245236644121, 559.8180591307826, 567.9403662615425, 575.7409165875356, 6772.966135169323, 6778.694958726863, 6794.898076528843, 6811.386975172233, 6827.590344579328, 6428.35716428357, 6561.818872602929, 6669.433098186648, 6684.66576671167, 6699.19609646843, 5282.128448805943, 5300.09898317285, 5337.092694241969, 5343.572514198864, 5346.144616861303, 5294.020606187848, 5306.893862122755, 5339.119045357531, 5346.3960811756315, 5352.895320713977, 5085.567726743213, 5248.501392367719, 5266.078436273637, 5272.261883336336, 5277.498637629419, 5211.348581427322, 5241.685498870061, 5257.526822585947, 5270.31078245226, 5281.498257665516, 4699.060187962421, 4717.976410117962, 4721.929171062437, 4724.244120940667, 4741.0, 6549.34506549346, 6611.140551728293, 6627.204735431731, 6632.535722499427, 6717.832054198635, 6203.627782333075, 6222.43998040174, 6233.532485063638, 6259.311475737665, 6266.026201746436, 7001.264867188923, 7100.28997100291, 7101.538400004035, 7105.218425973138, 7156.677949492233, 5253.317068781034, 5257.973710131425, 5266.8419947401635, 5298.655587386807, 5307.973460132714, 7507.662155203029, 7551.225462016422, 7575.3182213600485, 7584.582847943361, 7588.468807183499, 5419.403865574784, 5426.968875913563, 5441.0, 5480.698561579072, 5504.40382280792, 5489.505944465017, 5507.476789704971, 5532.917006244909, 5537.141743029837, 5537.972310138423, 6114.174586430775, 6232.127502149703, 6233.438990490878, 6245.5628106033055, 6250.406211409909, 7106.786796396116, 7121.89317160243, 7163.140423149255, 7232.240614735377, 7239.312265334785, 1819.9363022294156, 1860.6510280047128, 1877.9952725291903, 1891.101726679816, 1914.444811004793, 1274.5921305182437, 1330.2683524061727, 1336.953206637763, 1337.1241836597044, 1346.4748747988292, 5492.972535137299, 6145.84635384119, 6237.126802247689, 6237.500999919996, 6246.094941043026, 7045.8238544035785, 7073.92926070743, 7132.893006604905, 7141.285871412817, 7244.746433874789, 6177.166082578884, 6196.287426945959, 6200.81397558074, 6207.392894984117, 6296.0, 3808.504894363715, 3829.751066180689, 3836.3478208704587, 3849.268638958589, 3934.6852251819787, 6925.272846351161, 7084.752033678797, 7104.21853596104, 7138.357547820721, 7138.785836424916, 7305.379042781423, 7391.6673749681395, 7438.218987006395, 7505.962470187614, 7614.923850761532, 7074.434045276263, 7085.89371159433, 7105.9644701778225, 7131.643417829087, 7154.177269614061, 7190.280971902977, 7201.3158749918675, 7205.423566114803, 7285.490015698851, 7301.963490182515, 7163.391111755559, 7205.927940720735, 7268.890966635399, 7300.196978332273, 7378.335949764332, 6961.0, 6963.2340442552295, 7006.439484841098, 7049.400800931979, 7150.7497237596435, 5343.732813359395, 5368.141097424394, 5421.159720243369, 5435.537979271806, 5451.781928722807, 7316.9634151828905, 7319.451041171827, 7375.299346562068, 7402.742793170987, 7408.259174082548, 6953.269906659728, 6974.442044636316, 7008.684609192496, 7045.471589630901, 7073.504854660125, 6995.965020174866, 7026.2271150174465, 7026.859462810613, 7044.647767611496, 7062.858742825218, 6911.274316196727, 6965.721371145098, 6990.0, 6995.845685461842, 7057.470689698191, 6368.808935731936, 7045.72373846164, 7059.5764254144915, 7120.821979450555, 7131.750388736369, 6749.358810912955, 6754.324567543403, 6766.748151591972, 6781.186257649114, 6934.271901450275], "value": 5454.173887656724, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [952.3238500664528, 976.2092704909024, 996.1881066930451, 1009.6264382178592, 1010.4442556593872, 19297.0, 19819.0, 19861.801381986177, 19872.60254794905, 23514.88242558789, 20110.798892011077, 20114.798852011474, 20333.59332813343, 20336.491587710316, 20348.186072557117, 1053.7997780421733, 1069.5294070608945, 1069.5507886687571, 1069.5561342043043, 1073.5115522437272, 1073.7906108308882, 1074.2158224496122, 1082.9603580562668, 1083.1118482844072, 1085.1535802074354, 1097.5225776787083, 1150.4822829726616, 1150.7180740718536, 1150.7583407484437, 1151.0216316131264, 1131.4964840645957, 1132.7224829916722, 1134.3420815926743, 1142.8571428571388, 1143.0112952296204, 814.8044469327318, 815.6900377856376, 815.963281652327, 816.7263966571206, 819.4427789103407, 20467.590648187102, 20895.0, 21110.0, 21143.259985900575, 21232.575348492945, 22204.55590888173, 22419.663705044437, 22672.659910101363, 22707.20524781641, 22750.54498910029, 17214.82785172145, 17326.306947722074, 17515.912420437817, 17531.824681753147, 17702.822971770376, 3948.032731980683, 3978.8607398740905, 3978.900527486807, 3980.2835489611857, 3985.60143985602, 131033.0, 131895.34052329772, 132804.0, 133187.0, 133587.33206333907, 17615.471535853943, 17972.1013949302, 17975.550611234823, 18215.817841821412, 18228.63542729138, 31015.844920775253, 31658.841705791325, 32548.83725581357, 32737.836310818293, 32737.836310818293, 8516.27611653004, 9405.341626086245, 9784.412935223909, 9978.301518893608, 10019.248556358303, 4522.389477420572, 4689.062187562433, 4690.554397332248, 4702.52974702527, 4708.105459962596, 5877.882442351129, 5960.493358064527, 5965.015772397506, 6006.33930267679, 6031.909521357183, 1223.4188760338768, 1238.6779437346174, 1257.1891130220956, 1259.9496020159274, 1262.236347010064, 736.3887972982412, 750.8122969257686, 752.7591170825393, 772.4901564967095, 777.4868586732729, 11921.34432606215, 12144.392780360946, 12305.138640295272, 12503.874961250453, 12634.494620215271, 750.8761054426068, 751.6392131776778, 758.4425447296233, 762.5310434082999, 763.2901401696475, 5843.970780146072, 6163.445289923929, 6227.813165605039, 6269.811905642838, 6269.937300627026, 129491.0, 131422.0, 131832.3408382952, 133796.33101834625, 134365.32817335852, 114485.0, 118905.0, 121026.0, 121323.39338303253, 121730.0, 101538.49230753799, 102946.0, 103633.48183259036, 106270.0, 106996.46501767442, 12431.502739890304, 12911.096223264281, 13257.734845303234, 13318.800217996932, 13367.799483007568, 50175.74912125416, 50439.0, 51425.0, 51937.480625194774, 52420.0, 84748.57625712079, 86878.0, 88523.0, 88544.0, 92837.53581232051, 16776.66446671084, 19150.659453838096, 19302.61394772125, 19431.514212144244, 19483.512912176742, 19612.8038719611, 19629.018549072774, 19828.107735151665, 20519.69220461724, 21457.777244598892, 18650.0, 18737.812621873607, 18927.71608425847, 18955.147018384483, 19150.233990640776, 18988.335408260646, 19345.322913697953, 20310.796892030892, 20687.379378618665, 20739.585208296052, 17888.821111789235, 18237.361692340703, 19898.60202795907, 20081.196752129752, 20173.092210850842, 13933.23367214813, 14006.719865602834, 14246.216458094905, 14402.711945760817, 14536.5639030825, 14651.267436627913, 14917.47788827386, 15090.773638395653, 15226.619334516725, 15324.540263792103], "value": 10014.492238758712, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1441.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 106.0, 99.0, 106.0, 105.0, 105.0, 107.0, 96.0, 105.0, 109.0], "value": 103.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 114.0, 140.0, 163.0, 115.0, 143.0, 153.0, 112.0, 186.0, 114.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 161.0, 162.0, 162.0, 164.0, 162.0, 161.0, 170.0], "value": 163.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 172.0, 74.0, 73.0, 73.0, 73.0, 152.0, 75.0], "value": 91.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 126.0, 77.0, 77.0, 77.0, 126.0, 78.0, 78.0, 129.0], "value": 92.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 115.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 151.0, 150.0, 150.0, 150.0, 150.0, 150.0, 150.0], "value": 150.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 95.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 43.0, 47.0, 45.0, 50.0, 43.0, 44.0, 53.0, 44.0, 44.0], "value": 45.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 39.0, 39.0, 38.0, 39.0, 38.0, 40.0, 39.0, 38.0], "value": 38.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 72.0, 72.0, 72.0, 73.0, 77.0, 72.0, 72.0, 76.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 124.0, 120.0, 119.0, 108.0, 119.0, 112.0, 108.0, 116.0, 148.0], "value": 120.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 156.0, 159.0, 152.0, 155.0, 155.0, 155.0, 153.0, 164.0, 161.0], "value": 156.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 60.0, 60.0, 60.0, 60.0, 59.0, 61.0, 60.0, 58.0], "value": 60.2, "unit": "score"}]}]} 16:38:40 INFO - Return code: 0 16:38:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:38:40 INFO - # TBPL SUCCESS # 16:38:40 INFO - Running post-action listener: _package_coverage_data 16:38:40 INFO - Running post-action listener: _resource_record_post_action 16:38:40 INFO - [mozharness: 2017-09-02 23:38:40.271616Z] Finished run-tests step (success) 16:38:40 INFO - Running post-run listener: _resource_record_post_run 16:38:40 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 386830336; Read time: 180; Write time: 213552 16:38:40 INFO - TinderboxPrint: CPU usage
13.1% 16:38:40 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 180 16:38:40 INFO - TinderboxPrint: I/O write bytes / time
386,830,336 / 213,552 16:38:40 INFO - TinderboxPrint: CPU idle
8,226.1 (86.9%) 16:38:40 INFO - TinderboxPrint: CPU user
1,188.4 (12.6%) 16:38:40 INFO - TinderboxPrint: Swap in / out
0 / 0 16:38:40 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107347968; Read time: 0; Write time: 94804 16:38:40 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:38:40 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 8192; Write bytes: 279482368; Read time: 180; Write time: 118748 16:38:40 INFO - Running post-run listener: _upload_blobber_files 16:38:40 INFO - Blob upload gear active. 16:38:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:38:40 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:38:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:38:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:38:41 INFO - (blobuploader) - INFO - Open directory for files ... 16:38:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:38:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:38:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:42 INFO - (blobuploader) - INFO - Done attempting. 16:38:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:38:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:38:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:49 INFO - (blobuploader) - INFO - Done attempting. 16:38:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:38:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:38:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:49 INFO - (blobuploader) - INFO - Done attempting. 16:38:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:38:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:38:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:50 INFO - (blobuploader) - INFO - Done attempting. 16:38:50 INFO - (blobuploader) - INFO - Iteration through files over. 16:38:50 INFO - Return code: 0 16:38:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:38:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:38:50 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09ed4981c418164dd110e5e477641a97c917a0b724c690b194797d27b72cdd459b97135b43441e9014063b793440094e1befc308baf0b241b5c5ffdd8f0c7f8a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68296da039e120d5f8bea7583cf8561feef990d13b6cfdb32b19f44819429b0486aee7f6d5687b858dee917c6933257654cffada8d4506a1e36db45f7b0f1750", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f4ec15322033613f4fcce26b05d8ef07de91b26411dce6bdf7b5c8c2004d78b2b94ba2ce2edd539823b8e50f140ca4df65295240f319a2f79f7281933d4fb6d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c347e0812899716a3da543aaf2a50f05fcdff8a1cb8aa0a00c336372fc9770c5ae7fef13959a60a4d6807e5800d1a611fc4bb59d3886e4f996e1ca6322883450"} 16:38:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:38:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:38:50 INFO - Contents: 16:38:50 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09ed4981c418164dd110e5e477641a97c917a0b724c690b194797d27b72cdd459b97135b43441e9014063b793440094e1befc308baf0b241b5c5ffdd8f0c7f8a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68296da039e120d5f8bea7583cf8561feef990d13b6cfdb32b19f44819429b0486aee7f6d5687b858dee917c6933257654cffada8d4506a1e36db45f7b0f1750", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f4ec15322033613f4fcce26b05d8ef07de91b26411dce6bdf7b5c8c2004d78b2b94ba2ce2edd539823b8e50f140ca4df65295240f319a2f79f7281933d4fb6d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c347e0812899716a3da543aaf2a50f05fcdff8a1cb8aa0a00c336372fc9770c5ae7fef13959a60a4d6807e5800d1a611fc4bb59d3886e4f996e1ca6322883450"} 16:38:51 INFO - Running post-run listener: copy_logs_to_upload_dir 16:38:51 INFO - Copying logs to upload dir... 16:38:51 INFO - mkdir: /builds/slave/test/build/upload/logs 16:38:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.302645 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-09-02 16:38:51.062395) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 16:38:51.066136) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260 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/09ed4981c418164dd110e5e477641a97c917a0b724c690b194797d27b72cdd459b97135b43441e9014063b793440094e1befc308baf0b241b5c5ffdd8f0c7f8a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68296da039e120d5f8bea7583cf8561feef990d13b6cfdb32b19f44819429b0486aee7f6d5687b858dee917c6933257654cffada8d4506a1e36db45f7b0f1750", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f4ec15322033613f4fcce26b05d8ef07de91b26411dce6bdf7b5c8c2004d78b2b94ba2ce2edd539823b8e50f140ca4df65295240f319a2f79f7281933d4fb6d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c347e0812899716a3da543aaf2a50f05fcdff8a1cb8aa0a00c336372fc9770c5ae7fef13959a60a4d6807e5800d1a611fc4bb59d3886e4f996e1ca6322883450"} build_url:https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011285 build_url: 'https://queue.taskcluster.net/v1/task/Nws720cCQeS6-DN01YUqhw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09ed4981c418164dd110e5e477641a97c917a0b724c690b194797d27b72cdd459b97135b43441e9014063b793440094e1befc308baf0b241b5c5ffdd8f0c7f8a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68296da039e120d5f8bea7583cf8561feef990d13b6cfdb32b19f44819429b0486aee7f6d5687b858dee917c6933257654cffada8d4506a1e36db45f7b0f1750", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f4ec15322033613f4fcce26b05d8ef07de91b26411dce6bdf7b5c8c2004d78b2b94ba2ce2edd539823b8e50f140ca4df65295240f319a2f79f7281933d4fb6d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c347e0812899716a3da543aaf2a50f05fcdff8a1cb8aa0a00c336372fc9770c5ae7fef13959a60a4d6807e5800d1a611fc4bb59d3886e4f996e1ca6322883450"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 16:38:51.094992) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:38:51.095354) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504394280.786653-1279719260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004508 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:38:51.177945) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 16:38:51.178262) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 16:38:51.178593) ========= ========= Total master_lag: 0.19 =========